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

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

20:03:38.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.807036 17303 mini_oidc.cc:150] Starting JWKS server
20:03:38.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.809214 17303 webserver.cc:533] Webserver started at http://127.0.0.1:41771/ using document root <none> and password file <none>
20:03:38.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.809415 17303 mini_oidc.cc:186] Starting OIDC Discovery server
20:03:38.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.809713 17303 webserver.cc:533] Webserver started at http://127.0.0.1:46339/ using document root <none> and password file <none>
20:03:38.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T20:03:38Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
20:03:38.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T20:03:38Z Disabled control of system clock
20:03:38.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.828981 17303 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:38.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:38.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/wal
20:03:38.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data
20:03:38.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:38.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:38.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:38.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:38.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:38.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:38.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/logs
20:03:38.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data/info.pb
20:03:38.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:38.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:38.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:38.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:38.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:38.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:38.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:38.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:38.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
20:03:38.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:38.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:38.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:38.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.254:41755
20:03:38.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.254
20:03:38.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:38.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:38.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:38.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:38.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:38.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:38.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:38.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:38.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41771/jwks.json with env {}
20:03:38.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.935376 17315 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:38.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.935690 17315 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:38.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.935762 17315 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:38.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.939603 17315 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
20:03:38.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.939711 17315 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:38.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.939742 17315 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
20:03:38.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.939770 17315 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
20:03:38.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.939800 17315 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
20:03:38.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.939821 17315 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:41771/jwks.json
20:03:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.944334 17315 master_runner.cc:387] Master server non-default flags:
20:03:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data
20:03:38.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/wal
20:03:38.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:38.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:38.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:38.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:38.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:38.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.254:41755
20:03:38.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:38.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:38.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:38.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41771/jwks.json
20:03:38.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:38.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:38.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data/info.pb
20:03:38.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.254
20:03:38.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:38.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:38.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.17315
20:03:38.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:38.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:38.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:38.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:38.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/logs
20:03:38.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:38.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:38.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:38.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
20:03:38.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:38.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
20:03:38.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:03:38.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 19:43:15 UTC on bdcb31816ec0
20:03:38.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11960
20:03:38.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.945883 17315 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:38.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.947326 17315 file_cache.cc:492] Constructed file cache file cache with capacity 419430
20:03:38.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.954366 17323 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:38.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.954553 17315 server_base.cc:1061] running on GCE node
20:03:38.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.954726 17320 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:38.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:38.954888 17321 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:38.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.955516 17315 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:38.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.956699 17315 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:38.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.957870 17315 hybrid_clock.cc:648] HybridClock initialized: now 1779134618957858 us; error 34 us; skew 500 ppm
20:03:38.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.959800 17315 webserver.cc:533] Webserver started at http://127.16.229.254:37795/ using document root <none> and password file <none>
20:03:38.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.960449 17315 fs_manager.cc:362] Metadata directory not provided
20:03:38.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.960510 17315 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:38.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.960672 17315 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:38.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.962425 17315 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data/instance:
20:03:38.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ecfeebd6c3f34db09f5c9119576c44db"
20:03:38.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:38 on dist-test-slave-5l46"
20:03:38.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.962956 17315 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/wal/instance:
20:03:38.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ecfeebd6c3f34db09f5c9119576c44db"
20:03:38.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:38 on dist-test-slave-5l46"
20:03:38.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.966718 17315 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
20:03:38.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.969051 17329 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:38.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.970264 17315 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
20:03:38.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.970376 17315 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data,/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/wal
20:03:38.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ecfeebd6c3f34db09f5c9119576c44db"
20:03:38.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:38 on dist-test-slave-5l46"
20:03:38.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.970450 17315 fs_report.cc:389] FS layout report
20:03:38.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:38.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/wal
20:03:38.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/wal
20:03:38.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data/data
20:03:38.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:38.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:38.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:38.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:38.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:38.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:38.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:38.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:38.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:38.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:38.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:38.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.984210 17315 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:38.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.984834 17315 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
20:03:38.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.984993 17315 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:38.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.992105 17315 rpc_server.cc:307] RPC server started. Bound to: 127.16.229.254:41755
20:03:38.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.992115 17381 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.16.229.254:41755 every 8 connection(s)
20:03:38.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.993079 17315 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/data/info.pb
20:03:38.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.996502 17382 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:38.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.997359 17303 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu as pid 17315
20:03:38.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.997534 17303 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-0/wal/instance
20:03:38.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:38.999017 17303 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:38.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:38.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/wal
20:03:38.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data
20:03:39.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:39.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:39.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:39.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/logs
20:03:39.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data/info.pb
20:03:39.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:39.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:39.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:39.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:39.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
20:03:39.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:39.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:39.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:39.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.253:38725
20:03:39.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.253
20:03:39.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:39.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:39.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:39.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:39.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:39.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41771/jwks.json with env {}
20:03:39.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.000775 17382 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.015785 17382 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.016736 17330 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.16.229.253:38725: connect: Connection refused (error 111)
20:03:39.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.017706 17382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.253:38725: Network error: Client connection negotiation failed: client connection to 127.16.229.253:38725: connect: Connection refused (error 111)
20:03:39.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.066949 17382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } attempt: 1
20:03:39.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.068552 17382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.253:38725: Network error: Client connection negotiation failed: client connection to 127.16.229.253:38725: connect: Connection refused (error 111)
20:03:39.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.110317 17384 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:39.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.110561 17384 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:39.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.110607 17384 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:39.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.114220 17384 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
20:03:39.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.114288 17384 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:39.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.114310 17384 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
20:03:39.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.114328 17384 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
20:03:39.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.114346 17384 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
20:03:39.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.114360 17384 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:41771/jwks.json
20:03:39.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.118639 17384 master_runner.cc:387] Master server non-default flags:
20:03:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data
20:03:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/wal
20:03:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.253:38725
20:03:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41771/jwks.json
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data/info.pb
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.253
20:03:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:39.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.17384
20:03:39.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:39.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:39.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/logs
20:03:39.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:39.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:39.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
20:03:39.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:39.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
20:03:39.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:03:39.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 19:43:15 UTC on bdcb31816ec0
20:03:39.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11960
20:03:39.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.119791 17384 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:39.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.120934 17384 file_cache.cc:492] Constructed file cache file cache with capacity 419430
20:03:39.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.127177 17395 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.127206 17393 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.127686 17384 server_base.cc:1061] running on GCE node
20:03:39.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.127938 17392 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.129683 17384 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:39.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.130858 17384 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:39.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.132035 17384 hybrid_clock.cc:648] HybridClock initialized: now 1779134619132023 us; error 38 us; skew 500 ppm
20:03:39.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.133863 17384 webserver.cc:533] Webserver started at http://127.16.229.253:33351/ using document root <none> and password file <none>
20:03:39.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.134528 17384 fs_manager.cc:362] Metadata directory not provided
20:03:39.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.134609 17384 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:39.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.134821 17384 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:39.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.136742 17382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } attempt: 2
20:03:39.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.136648 17384 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data/instance:
20:03:39.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a47cf35ffdaf4a61a44ef751de8c567a"
20:03:39.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.137285 17384 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/wal/instance:
20:03:39.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a47cf35ffdaf4a61a44ef751de8c567a"
20:03:39.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.138258 17382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.253:38725: Network error: Client connection negotiation failed: client connection to 127.16.229.253:38725: connect: Connection refused (error 111)
20:03:39.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.141193 17384 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.003s	sys 0.001s
20:03:39.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.143618 17401 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:39.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.144714 17384 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
20:03:39.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.144850 17384 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data,/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/wal
20:03:39.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a47cf35ffdaf4a61a44ef751de8c567a"
20:03:39.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.144956 17384 fs_report.cc:389] FS layout report
20:03:39.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:39.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/wal
20:03:39.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/wal
20:03:39.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data/data
20:03:39.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:39.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:39.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:39.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:39.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:39.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:39.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:39.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:39.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:39.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:39.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:39.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.177502 17384 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:39.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.178364 17384 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
20:03:39.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.178589 17384 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:39.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.185765 17384 rpc_server.cc:307] RPC server started. Bound to: 127.16.229.253:38725
20:03:39.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.185776 17453 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.16.229.253:38725 every 8 connection(s)
20:03:39.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.186965 17384 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/data/info.pb
20:03:39.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.190110 17454 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:39.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.194293 17454 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.196998 17303 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu as pid 17384
20:03:39.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.197108 17303 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-1/wal/instance
20:03:39.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.197821 17303 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:39.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:39.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/wal
20:03:39.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data
20:03:39.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:39.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:39.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:39.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/logs
20:03:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data/info.pb
20:03:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
20:03:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.252:39591
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.252
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:39.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:39.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41771/jwks.json with env {}
20:03:39.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.206358 17454 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.213201 17454 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.214115 17404 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.16.229.252:39591: connect: Connection refused (error 111)
20:03:39.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.219503 17454 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.252:39591: Network error: Client connection negotiation failed: client connection to 127.16.229.252:39591: connect: Connection refused (error 111)
20:03:39.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.229445 17382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } attempt: 3
20:03:39.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.234258 17382 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.235622 17382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.252:39591: Network error: Client connection negotiation failed: client connection to 127.16.229.252:39591: connect: Connection refused (error 111)
20:03:39.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.266825 17382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } attempt: 1
20:03:39.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.268747 17454 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } attempt: 1
20:03:39.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.269052 17382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.252:39591: Network error: Client connection negotiation failed: client connection to 127.16.229.252:39591: connect: Connection refused (error 111)
20:03:39.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.270171 17454 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.252:39591: Network error: Client connection negotiation failed: client connection to 127.16.229.252:39591: connect: Connection refused (error 111)
20:03:39.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.316699 17457 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:39.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.316936 17457 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:39.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.316982 17457 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:39.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.320822 17457 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
20:03:39.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.320890 17457 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:39.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.320914 17457 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
20:03:39.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.320931 17457 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
20:03:39.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.320950 17457 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
20:03:39.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.320964 17457 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:41771/jwks.json
20:03:39.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.324980 17457 master_runner.cc:387] Master server non-default flags:
20:03:39.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:39.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:39.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data
20:03:39.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/wal
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.252:39591
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:39.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41771/jwks.json
20:03:39.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data/info.pb
20:03:39.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.252
20:03:39.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:39.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.17457
20:03:39.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:39.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:39.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/logs
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:03:39.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 19:43:15 UTC on bdcb31816ec0
20:03:39.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11960
20:03:39.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.326450 17457 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:39.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.327812 17457 file_cache.cc:492] Constructed file cache file cache with capacity 419430
20:03:39.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.333415 17464 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.333425 17465 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.333436 17467 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.333869 17457 server_base.cc:1061] running on GCE node
20:03:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.334923 17457 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.335776 17457 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:39.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.336925 17457 hybrid_clock.cc:648] HybridClock initialized: now 1779134619336913 us; error 34 us; skew 500 ppm
20:03:39.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.338347 17454 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } attempt: 2
20:03:39.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.338690 17457 webserver.cc:533] Webserver started at http://127.16.229.252:32855/ using document root <none> and password file <none>
20:03:39.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.339388 17457 fs_manager.cc:362] Metadata directory not provided
20:03:39.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.339789 17454 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.252:39591: Network error: Client connection negotiation failed: client connection to 127.16.229.252:39591: connect: Connection refused (error 111)
20:03:39.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.339802 17457 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:39.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.340050 17457 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:39.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.341648 17457 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data/instance:
20:03:39.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8ad901378a524b2d94f11a4f6bec9337"
20:03:39.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.342072 17457 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/wal/instance:
20:03:39.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8ad901378a524b2d94f11a4f6bec9337"
20:03:39.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.344233 17382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } attempt: 2
20:03:39.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.345748 17457 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
20:03:39.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.345846 17382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.16.229.252:39591: Network error: Client connection negotiation failed: client connection to 127.16.229.252:39591: connect: Connection refused (error 111)
20:03:39.348 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.348115 17473 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:39.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.349205 17457 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
20:03:39.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.349336 17457 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data,/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/wal
20:03:39.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8ad901378a524b2d94f11a4f6bec9337"
20:03:39.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.349435 17457 fs_report.cc:389] FS layout report
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/wal
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/wal
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data/data
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:39.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:39.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:39.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:39.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:39.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:39.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:39.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:39.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:39.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.372431 17457 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:39.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.373353 17457 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
20:03:39.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.373577 17457 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:39.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.380818 17457 rpc_server.cc:307] RPC server started. Bound to: 127.16.229.252:39591
20:03:39.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.380846 17525 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.16.229.252:39591 every 8 connection(s)
20:03:39.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.381867 17457 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/data/info.pb
20:03:39.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.384610 17526 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:39.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.387873 17303 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu as pid 17457
20:03:39.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.387964 17303 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/master-2/wal/instance
20:03:39.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.388005 17526 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.397271 17526 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.403739 17526 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } has no permanent_uuid. Determining permanent_uuid...
20:03:39.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.410307 17526 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337: Bootstrap starting.
20:03:39.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.412428 17526 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337: Neither blocks nor log segments found. Creating new log.
20:03:39.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.413321 17526 log.cc:826] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337: Log is configured to *not* fsync() on all Append() calls
20:03:39.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.414932 17526 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337: No bootstrap required, opened a new log
20:03:39.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.417986 17526 raft_consensus.cc:359] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.418152 17526 raft_consensus.cc:385] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:39.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.418186 17526 raft_consensus.cc:740] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8ad901378a524b2d94f11a4f6bec9337, State: Initialized, Role: FOLLOWER
20:03:39.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.418520 17526 consensus_queue.cc:260] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [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: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.418947 17535 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [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: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.419093 17535 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: This master's current role is: FOLLOWER
20:03:39.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.419484 17526 sys_catalog.cc:565] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: configured and running, proceeding with master startup.
20:03:39.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.424026 17546 catalog_manager.cc:1593] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
20:03:39.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.424468 17546 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
20:03:39.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.422817 17457 master_runner.cc:187] Error getting master registration for 127.16.229.254:41755: OK, instance_id { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" instance_seqno: 1779134618990239 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
20:03:39.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.425153 17457 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
20:03:39.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.430964 17454 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } attempt: 3
20:03:39.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.437057 17454 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: Bootstrap starting.
20:03:39.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.439953 17454 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: Neither blocks nor log segments found. Creating new log.
20:03:39.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.440881 17454 log.cc:826] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: Log is configured to *not* fsync() on all Append() calls
20:03:39.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.442529 17454 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: No bootstrap required, opened a new log
20:03:39.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.445050 17454 raft_consensus.cc:359] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.445194 17382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } attempt: 3
20:03:39.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.445309 17454 raft_consensus.cc:385] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:39.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.445358 17454 raft_consensus.cc:740] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a47cf35ffdaf4a61a44ef751de8c567a, State: Initialized, Role: FOLLOWER
20:03:39.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.446128 17454 consensus_queue.cc:260] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [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: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.447849 17549 sys_catalog.cc:455] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [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: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.448232 17549 sys_catalog.cc:458] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [sys.catalog]: This master's current role is: FOLLOWER
20:03:39.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.448437 17454 sys_catalog.cc:565] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [sys.catalog]: configured and running, proceeding with master startup.
20:03:39.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.452416 17384 master_runner.cc:187] Error getting master registration for 127.16.229.254:41755: OK, instance_id { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" instance_seqno: 1779134618990239 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
20:03:39.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.454864 17384 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
20:03:39.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.454972 17560 catalog_manager.cc:1593] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
20:03:39.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.455022 17560 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
20:03:39.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.457841 17382 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: Bootstrap starting.
20:03:39.460 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.460479 17382 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: Neither blocks nor log segments found. Creating new log.
20:03:39.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.461736 17382 log.cc:826] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: Log is configured to *not* fsync() on all Append() calls
20:03:39.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.463704 17382 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: No bootstrap required, opened a new log
20:03:39.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.467104 17382 raft_consensus.cc:359] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.467315 17382 raft_consensus.cc:385] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:39.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.467371 17382 raft_consensus.cc:740] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: ecfeebd6c3f34db09f5c9119576c44db, State: Initialized, Role: FOLLOWER
20:03:39.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.467917 17382 consensus_queue.cc:260] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [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: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.469632 17382 sys_catalog.cc:565] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [sys.catalog]: configured and running, proceeding with master startup.
20:03:39.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.470691 17563 sys_catalog.cc:455] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [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: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.471122 17563 sys_catalog.cc:458] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [sys.catalog]: This master's current role is: FOLLOWER
20:03:39.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.475419 17574 catalog_manager.cc:1593] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
20:03:39.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.476220 17574 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
20:03:39.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.479056 17315 master_runner.cc:264] No leader master found from master ecfeebd6c3f34db09f5c9119576c44db
20:03:39.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.479122 17315 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
20:03:39.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.486495 17335 tablet.cc:2404] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:39.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.624982 17479 tablet.cc:2404] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:39.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.680174 17407 tablet.cc:2404] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:39.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.723130 17535 raft_consensus.cc:493] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
20:03:39.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.723299 17535 raft_consensus.cc:515] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.724159 17535 leader_election.cc:290] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers ecfeebd6c3f34db09f5c9119576c44db (127.16.229.254:41755), a47cf35ffdaf4a61a44ef751de8c567a (127.16.229.253:38725)
20:03:39.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.724712 17428 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8ad901378a524b2d94f11a4f6bec9337" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" is_pre_election: true
20:03:39.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.724721 17356 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8ad901378a524b2d94f11a4f6bec9337" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "ecfeebd6c3f34db09f5c9119576c44db" is_pre_election: true
20:03:39.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.725005 17428 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 8ad901378a524b2d94f11a4f6bec9337 in term 0.
20:03:39.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.725013 17356 raft_consensus.cc:2468] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 8ad901378a524b2d94f11a4f6bec9337 in term 0.
20:03:39.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.725513 17475 leader_election.cc:304] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [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: 8ad901378a524b2d94f11a4f6bec9337, ecfeebd6c3f34db09f5c9119576c44db; no voters: 
20:03:39.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.725823 17535 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Leader pre-election won for term 1
20:03:39.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.725895 17535 raft_consensus.cc:493] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
20:03:39.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.725945 17535 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 0 FOLLOWER]: Advancing to term 1
20:03:39.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.727164 17535 raft_consensus.cc:515] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.727609 17535 leader_election.cc:290] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [CANDIDATE]: Term 1 election: Requested vote from peers ecfeebd6c3f34db09f5c9119576c44db (127.16.229.254:41755), a47cf35ffdaf4a61a44ef751de8c567a (127.16.229.253:38725)
20:03:39.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.727924 17356 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8ad901378a524b2d94f11a4f6bec9337" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "ecfeebd6c3f34db09f5c9119576c44db"
20:03:39.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.728080 17356 raft_consensus.cc:3060] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [term 0 FOLLOWER]: Advancing to term 1
20:03:39.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.728365 17428 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8ad901378a524b2d94f11a4f6bec9337" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a47cf35ffdaf4a61a44ef751de8c567a"
20:03:39.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.728477 17428 raft_consensus.cc:3060] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [term 0 FOLLOWER]: Advancing to term 1
20:03:39.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.729301 17356 raft_consensus.cc:2468] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 8ad901378a524b2d94f11a4f6bec9337 in term 1.
20:03:39.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.729646 17428 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 8ad901378a524b2d94f11a4f6bec9337 in term 1.
20:03:39.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.729651 17475 leader_election.cc:304] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [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: 8ad901378a524b2d94f11a4f6bec9337, ecfeebd6c3f34db09f5c9119576c44db; no voters: 
20:03:39.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.729866 17535 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 1 FOLLOWER]: Leader election won for term 1
20:03:39.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.730118 17535 raft_consensus.cc:697] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [term 1 LEADER]: Becoming Leader. State: Replica: 8ad901378a524b2d94f11a4f6bec9337, State: Running, Role: LEADER
20:03:39.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.730463 17535 consensus_queue.cc:237] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [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: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } }
20:03:39.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.732745 17576 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8ad901378a524b2d94f11a4f6bec9337. Latest consensus state: current_term: 1 leader_uuid: "8ad901378a524b2d94f11a4f6bec9337" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.733582 17576 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: This master's current role is: LEADER
20:03:39.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.734017 17578 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
20:03:39.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.735101 17578 catalog_manager.cc:1519] Initializing Kudu cluster ID...
20:03:39.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.740415 17356 raft_consensus.cc:1275] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [term 1 FOLLOWER]: Refusing update from remote peer 8ad901378a524b2d94f11a4f6bec9337: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:39.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.741215 17428 raft_consensus.cc:1275] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [term 1 FOLLOWER]: Refusing update from remote peer 8ad901378a524b2d94f11a4f6bec9337: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:39.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.741191 17576 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [LEADER]: Connected to new peer: Peer: permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:03:39.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.742209 17576 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [LEADER]: Connected to new peer: Peer: permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:03:39.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.745543 17549 sys_catalog.cc:455] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8ad901378a524b2d94f11a4f6bec9337. Latest consensus state: current_term: 1 leader_uuid: "8ad901378a524b2d94f11a4f6bec9337" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.745688 17549 sys_catalog.cc:458] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [sys.catalog]: This master's current role is: FOLLOWER
20:03:39.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.746052 17563 sys_catalog.cc:455] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8ad901378a524b2d94f11a4f6bec9337. Latest consensus state: current_term: 1 leader_uuid: "8ad901378a524b2d94f11a4f6bec9337" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.746168 17563 sys_catalog.cc:458] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [sys.catalog]: This master's current role is: FOLLOWER
20:03:39.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.747997 17535 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "8ad901378a524b2d94f11a4f6bec9337" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.748135 17535 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: This master's current role is: LEADER
20:03:39.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.748061 17579 mvcc.cc:204] Tried to move back new op lower bound from 7287335402449289216 to 7287335402419376128. Current Snapshot: MvccSnapshot[applied={T|T < 7287335402449289216}]
20:03:39.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.748530 17549 sys_catalog.cc:455] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "8ad901378a524b2d94f11a4f6bec9337" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.748651 17549 sys_catalog.cc:458] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a [sys.catalog]: This master's current role is: FOLLOWER
20:03:39.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.748916 17580 mvcc.cc:204] Tried to move back new op lower bound from 7287335402449289216 to 7287335402419376128. Current Snapshot: MvccSnapshot[applied={T|T < 7287335402449289216}]
20:03:39.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.749469 17576 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "8ad901378a524b2d94f11a4f6bec9337" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.749595 17576 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 [sys.catalog]: This master's current role is: LEADER
20:03:39.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.750144 17581 mvcc.cc:204] Tried to move back new op lower bound from 7287335402449289216 to 7287335402419376128. Current Snapshot: MvccSnapshot[applied={T|T < 7287335402449289216}]
20:03:39.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.750336 17563 sys_catalog.cc:455] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "8ad901378a524b2d94f11a4f6bec9337" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "ecfeebd6c3f34db09f5c9119576c44db" member_type: VOTER last_known_addr { host: "127.16.229.254" port: 41755 } } peers { permanent_uuid: "a47cf35ffdaf4a61a44ef751de8c567a" member_type: VOTER last_known_addr { host: "127.16.229.253" port: 38725 } } peers { permanent_uuid: "8ad901378a524b2d94f11a4f6bec9337" member_type: VOTER last_known_addr { host: "127.16.229.252" port: 39591 } } }
20:03:39.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.750444 17563 sys_catalog.cc:458] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db [sys.catalog]: This master's current role is: FOLLOWER
20:03:39.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.751356 17578 catalog_manager.cc:1382] Generated new cluster ID: 65cf75683f6b41848bac2b62017d5cdd
20:03:39.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.751418 17578 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
20:03:39.767 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.766984 17578 catalog_manager.cc:1405] Generated new certificate authority record
20:03:39.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.768088 17578 catalog_manager.cc:1539] Loading token signing keys...
20:03:39.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.776295 17578 catalog_manager.cc:6069] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337: Generated new TSK 0
20:03:39.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.777076 17578 catalog_manager.cc:1549] Initializing in-progress tserver states...
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.809202 17303 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/wal
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:39.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/logs
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data/info.pb
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:03:39.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:39.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.193:0
20:03:39.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.229.193
20:03:39.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.193
20:03:39.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:39.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:39.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
20:03:39.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.913954 17588 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:39.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.914212 17588 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:39.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.914275 17588 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:39.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.917944 17588 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:39.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.918097 17588 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.16.229.193
20:03:39.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.922348 17588 tablet_server_runner.cc:78] Tablet server non-default flags:
20:03:39.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:39.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/wal
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.193:0
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data/info.pb
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.193
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.17588
20:03:39.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.229.193
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/logs
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:03:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 19:43:15 UTC on bdcb31816ec0
20:03:39.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11960
20:03:39.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.923774 17588 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:39.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.925302 17588 file_cache.cc:492] Constructed file cache file cache with capacity 419430
20:03:39.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.931792 17593 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.931792 17594 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:39.931800 17596 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:39.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.932897 17588 server_base.cc:1061] running on GCE node
20:03:39.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.933350 17588 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:39.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.934000 17588 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:39.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.935191 17588 hybrid_clock.cc:648] HybridClock initialized: now 1779134619935131 us; error 77 us; skew 500 ppm
20:03:39.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.937466 17588 webserver.cc:533] Webserver started at http://127.16.229.193:38083/ using document root <none> and password file <none>
20:03:39.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.938174 17588 fs_manager.cc:362] Metadata directory not provided
20:03:39.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.938251 17588 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:39.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.938464 17588 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:39.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.940483 17588 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data/instance:
20:03:39.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "23071a6526834ab888695ec5316a5248"
20:03:39.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.941072 17588 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/wal/instance:
20:03:39.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "23071a6526834ab888695ec5316a5248"
20:03:39.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.945488 17588 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.003s	sys 0.001s
20:03:39.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.948319 17602 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:39.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.949631 17588 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
20:03:39.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.949780 17588 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data,/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/wal
20:03:39.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "23071a6526834ab888695ec5316a5248"
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:39 on dist-test-slave-5l46"
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.949887 17588 fs_report.cc:389] FS layout report
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/wal
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/wal
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data/data
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:39.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:39.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:39.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.968153 17588 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:39.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.968787 17588 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
20:03:39.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.968940 17588 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:39.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.969563 17588 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:03:39.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.970609 17588 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:03:39.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.970655 17588 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:03:39.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.970695 17588 ts_tablet_manager.cc:616] Registered 0 tablets
20:03:39.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.970710 17588 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:03:39.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.979964 17588 rpc_server.cc:307] RPC server started. Bound to: 127.16.229.193:40315
20:03:39.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.979987 17715 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.16.229.193:40315 every 8 connection(s)
20:03:39.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.980916 17588 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/data/info.pb
20:03:39.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.985162 17303 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu as pid 17588
20:03:39.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.985267 17303 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-0/wal/instance
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.990110 17303 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/wal
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/logs
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data/info.pb
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:39.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.194:0
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.229.194
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.194
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
20:03:39.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.991415 17718 heartbeater.cc:344] Connected to a master server at 127.16.229.253:38725
20:03:39.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.991674 17718 heartbeater.cc:461] Registering TS with master...
20:03:39.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.992128 17716 heartbeater.cc:344] Connected to a master server at 127.16.229.252:39591
20:03:39.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.992211 17716 heartbeater.cc:461] Registering TS with master...
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.992302 17718 heartbeater.cc:507] Master 127.16.229.253:38725 requested a full tablet report, sending...
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.992388 17716 heartbeater.cc:507] Master 127.16.229.252:39591 requested a full tablet report, sending...
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.993862 17490 ts_manager.cc:194] Registered new tserver with Master: 23071a6526834ab888695ec5316a5248 (127.16.229.193:40315)
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.993955 17418 ts_manager.cc:194] Registered new tserver with Master: 23071a6526834ab888695ec5316a5248 (127.16.229.193:40315)
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.994102 17717 heartbeater.cc:344] Connected to a master server at 127.16.229.254:41755
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.994176 17717 heartbeater.cc:461] Registering TS with master...
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.994360 17717 heartbeater.cc:507] Master 127.16.229.254:41755 requested a full tablet report, sending...
20:03:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.995401 17490 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.16.229.193:38863
20:03:39.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:39.996001 17346 ts_manager.cc:194] Registered new tserver with Master: 23071a6526834ab888695ec5316a5248 (127.16.229.193:40315)
20:03:40.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.104259 17726 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:40.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.104503 17726 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:40.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.104548 17726 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:40.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.108886 17726 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:40.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.109040 17726 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.16.229.194
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.113085 17726 tablet_server_runner.cc:78] Tablet server non-default flags:
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/wal
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.194:0
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data/info.pb
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.194
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:40.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.17726
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.229.194
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/logs
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 19:43:15 UTC on bdcb31816ec0
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11960
20:03:40.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.114220 17726 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:40.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.115254 17726 file_cache.cc:492] Constructed file cache file cache with capacity 419430
20:03:40.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.121856 17734 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:40.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.121860 17731 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:40.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.121872 17732 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:40.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.122225 17726 server_base.cc:1061] running on GCE node
20:03:40.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.122537 17726 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:40.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.123083 17726 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:40.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.124254 17726 hybrid_clock.cc:648] HybridClock initialized: now 1779134620124240 us; error 34 us; skew 500 ppm
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.126189 17726 webserver.cc:533] Webserver started at http://127.16.229.194:35995/ using document root <none> and password file <none>
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.126838 17726 fs_manager.cc:362] Metadata directory not provided
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.126926 17726 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.127166 17726 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:40.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.128775 17726 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data/instance:
20:03:40.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "95ecd370df594a07882aad7da369b0b3"
20:03:40.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:40 on dist-test-slave-5l46"
20:03:40.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.129359 17726 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/wal/instance:
20:03:40.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "95ecd370df594a07882aad7da369b0b3"
20:03:40.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:40 on dist-test-slave-5l46"
20:03:40.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.133244 17726 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.002s	sys 0.002s
20:03:40.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.135480 17740 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:40.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.136600 17726 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.136698 17726 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data,/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/wal
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "95ecd370df594a07882aad7da369b0b3"
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:40 on dist-test-slave-5l46"
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.136773 17726 fs_report.cc:389] FS layout report
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/wal
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/wal
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data/data
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:40.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:40.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:40.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:40.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:40.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:40.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:40.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:40.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.155076 17726 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:40.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.155748 17726 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
20:03:40.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.155972 17726 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:40.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.156690 17726 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:03:40.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.157843 17726 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:03:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.157919 17726 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:03:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.157975 17726 ts_tablet_manager.cc:616] Registered 0 tablets
20:03:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.157999 17726 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:03:40.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.167112 17726 rpc_server.cc:307] RPC server started. Bound to: 127.16.229.194:44513
20:03:40.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.167155 17853 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.16.229.194:44513 every 8 connection(s)
20:03:40.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.168224 17726 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/data/info.pb
20:03:40.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.171578 17303 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu as pid 17726
20:03:40.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.171698 17303 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-1/wal/instance
20:03:40.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.175873 17303 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:40.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu
20:03:40.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/wal
20:03:40.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data
20:03:40.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:40.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/logs
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data/info.pb
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:40.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.195:0
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.229.195
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.195
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:40.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
20:03:40.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.179013 17854 heartbeater.cc:344] Connected to a master server at 127.16.229.252:39591
20:03:40.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.179301 17854 heartbeater.cc:461] Registering TS with master...
20:03:40.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.179425 17856 heartbeater.cc:344] Connected to a master server at 127.16.229.253:38725
20:03:40.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.179483 17856 heartbeater.cc:461] Registering TS with master...
20:03:40.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.179968 17854 heartbeater.cc:507] Master 127.16.229.252:39591 requested a full tablet report, sending...
20:03:40.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.179968 17856 heartbeater.cc:507] Master 127.16.229.253:38725 requested a full tablet report, sending...
20:03:40.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.180390 17855 heartbeater.cc:344] Connected to a master server at 127.16.229.254:41755
20:03:40.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.180455 17855 heartbeater.cc:461] Registering TS with master...
20:03:40.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.180645 17855 heartbeater.cc:507] Master 127.16.229.254:41755 requested a full tablet report, sending...
20:03:40.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.181149 17490 ts_manager.cc:194] Registered new tserver with Master: 95ecd370df594a07882aad7da369b0b3 (127.16.229.194:44513)
20:03:40.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.181205 17418 ts_manager.cc:194] Registered new tserver with Master: 95ecd370df594a07882aad7da369b0b3 (127.16.229.194:44513)
20:03:40.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.181383 17346 ts_manager.cc:194] Registered new tserver with Master: 95ecd370df594a07882aad7da369b0b3 (127.16.229.194:44513)
20:03:40.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.181936 17490 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.16.229.194:58053
20:03:40.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.284365 17861 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:40.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.284624 17861 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:40.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.284687 17861 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:40.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.288192 17861 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:40.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.288359 17861 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.16.229.195
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.292299 17861 tablet_server_runner.cc:78] Tablet server non-default flags:
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.229.212:40617
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/wal
20:03:40.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.229.195:0
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data/info.pb
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.229.195
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.229.254:41755,127.16.229.253:38725,127.16.229.252:39591
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.17861
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.229.195
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/logs
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:40.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
20:03:40.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:03:40.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 19:43:15 UTC on bdcb31816ec0
20:03:40.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11960
20:03:40.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.293844 17861 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:40.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.295033 17861 file_cache.cc:492] Constructed file cache file cache with capacity 419430
20:03:40.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.301604 17866 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:40.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.301556 17867 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:40.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.301681 17861 server_base.cc:1061] running on GCE node
20:03:40.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:40.301571 17869 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
20:03:40.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.302487 17861 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:40.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.302992 17861 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:40.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.304177 17861 hybrid_clock.cc:648] HybridClock initialized: now 1779134620304157 us; error 33 us; skew 500 ppm
20:03:40.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.305979 17861 webserver.cc:533] Webserver started at http://127.16.229.195:37785/ using document root <none> and password file <none>
20:03:40.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.306596 17861 fs_manager.cc:362] Metadata directory not provided
20:03:40.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.306681 17861 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:40.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.306912 17861 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:40.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.308549 17861 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data/instance:
20:03:40.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "7e257c72ab6f432a9202fd3d3c4a9795"
20:03:40.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:40 on dist-test-slave-5l46"
20:03:40.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.309083 17861 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/wal/instance:
20:03:40.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "7e257c72ab6f432a9202fd3d3c4a9795"
20:03:40.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:40 on dist-test-slave-5l46"
20:03:40.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.312572 17861 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.002s	sys 0.000s
20:03:40.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.314963 17875 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.315997 17861 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.316140 17861 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data,/tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/wal
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "7e257c72ab6f432a9202fd3d3c4a9795"
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 20:03:40 on dist-test-slave-5l46"
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.316258 17861 fs_report.cc:389] FS layout report
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/wal
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/wal
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data/data
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:40.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:40.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:40.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:40.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.327441 17861 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:40.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.328217 17861 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
20:03:40.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.328434 17861 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:40.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.329041 17861 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:03:40.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.330156 17861 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:03:40.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.330231 17861 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:03:40.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.330286 17861 ts_tablet_manager.cc:616] Registered 0 tablets
20:03:40.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.330310 17861 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:03:40.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.339571 17861 rpc_server.cc:307] RPC server started. Bound to: 127.16.229.195:45329
20:03:40.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.339599 17988 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.16.229.195:45329 every 8 connection(s)
20:03:40.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.340632 17861 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/data/info.pb
20:03:40.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.343189 17303 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu as pid 17861
20:03:40.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.343300 17303 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskfxOqvD/test-tmp/mini-kudu-cluster5083229126508689643/ts-2/wal/instance
20:03:40.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.351096 17990 heartbeater.cc:344] Connected to a master server at 127.16.229.254:41755
20:03:40.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.351253 17991 heartbeater.cc:344] Connected to a master server at 127.16.229.253:38725
20:03:40.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.351265 17989 heartbeater.cc:344] Connected to a master server at 127.16.229.252:39591
20:03:40.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.351372 17990 heartbeater.cc:461] Registering TS with master...
20:03:40.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.351372 17989 heartbeater.cc:461] Registering TS with master...
20:03:40.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.351372 17991 heartbeater.cc:461] Registering TS with master...
20:03:40.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.352035 17989 heartbeater.cc:507] Master 127.16.229.252:39591 requested a full tablet report, sending...
20:03:40.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.352032 17990 heartbeater.cc:507] Master 127.16.229.254:41755 requested a full tablet report, sending...
20:03:40.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.353113 17490 ts_manager.cc:194] Registered new tserver with Master: 7e257c72ab6f432a9202fd3d3c4a9795 (127.16.229.195:45329)
20:03:40.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.353233 17346 ts_manager.cc:194] Registered new tserver with Master: 7e257c72ab6f432a9202fd3d3c4a9795 (127.16.229.195:45329)
20:03:40.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.353873 17490 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.16.229.195:59693
20:03:40.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.355197 17991 heartbeater.cc:507] Master 127.16.229.253:38725 requested a full tablet report, sending...
20:03:40.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.356036 17418 ts_manager.cc:194] Registered new tserver with Master: 7e257c72ab6f432a9202fd3d3c4a9795 (127.16.229.195:45329)
20:03:40.364 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.364174 17303 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
20:03:40.365 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
20:03:40.366 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

20:03:40.374 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.16.229.254"
      port: 41755
    }
    bound_http_address {
      host: "127.16.229.254"
      port: 37795
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.16.229.253"
      port: 38725
    }
    bound_http_address {
      host: "127.16.229.253"
      port: 33351
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.16.229.252"
      port: 39591
    }
    bound_http_address {
      host: "127.16.229.252"
      port: 32855
    }
  }
}

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

20:03:40.380 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.16.229.193"
      port: 40315
    }
    bound_http_address {
      host: "127.16.229.193"
      port: 38083
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.16.229.194"
      port: 44513
    }
    bound_http_address {
      host: "127.16.229.194"
      port: 35995
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.16.229.195"
      port: 45329
    }
    bound_http_address {
      host: "127.16.229.195"
      port: 37785
    }
  }
}

20:03:40.381 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
20:03:40.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.456586 17560 catalog_manager.cc:1294] Loaded cluster ID: 65cf75683f6b41848bac2b62017d5cdd
20:03:40.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.456676 17560 catalog_manager.cc:1587] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: loading cluster ID for follower catalog manager: success
20:03:40.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.459185 17560 catalog_manager.cc:1609] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: acquiring CA information for follower catalog manager: success
20:03:40.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.460564 17560 catalog_manager.cc:1637] T 00000000000000000000000000000000 P a47cf35ffdaf4a61a44ef751de8c567a: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
20:03:40.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.478102 17574 catalog_manager.cc:1294] Loaded cluster ID: 65cf75683f6b41848bac2b62017d5cdd
20:03:40.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.478183 17574 catalog_manager.cc:1587] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: loading cluster ID for follower catalog manager: success
20:03:40.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.481148 17574 catalog_manager.cc:1609] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: acquiring CA information for follower catalog manager: success
20:03:40.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.482653 17574 catalog_manager.cc:1637] T 00000000000000000000000000000000 P ecfeebd6c3f34db09f5c9119576c44db: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
20:03:40.572 [DEBUG - main] (CompositeStrategy.java:59) Unable to load modern Subject methods, relying only on legacy methods
java.lang.NoSuchMethodException: javax.security.auth.Subject.current()
	at java.base/java.lang.Class.getDeclaredMethod(Class.java:2677)
	at org.apache.kudu.client.internals.ModernStrategy.<init>(ModernStrategy.java:51)
	at org.apache.kudu.client.internals.CompositeStrategy.<init>(CompositeStrategy.java:52)
	at org.apache.kudu.client.internals.CompositeStrategy.<clinit>(CompositeStrategy.java:41)
	at org.apache.kudu.client.internals.SecurityManagerCompatibility.get(SecurityManagerCompatibility.java:43)
	at org.apache.kudu.client.SecurityContext.setupSubject(SecurityContext.java:155)
	at org.apache.kudu.client.SecurityContext.<init>(SecurityContext.java:138)
	at org.apache.kudu.client.AsyncKuduClient.<init>(AsyncKuduClient.java:431)
	at org.apache.kudu.client.AsyncKuduClient$AsyncKuduClientBuilder.build(AsyncKuduClient.java:3109)
	at org.apache.kudu.test.KuduTestHarness.before(KuduTestHarness.java:161)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:50)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.doOneAttemptAndReport(RetryRule.java:184)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:244)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.runner.JUnitCore.runMain(JUnitCore.java:77)
	at org.junit.runner.JUnitCore.main(JUnitCore.java:36)
20:03:40.637 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
20:03:40.800 [DEBUG - main] (Connection.java:748) [peer master-127.16.229.254:41755(127.16.229.254:41755)] connecting to peer
20:03:40.853 [DEBUG - main] (Connection.java:748) [peer master-127.16.229.252:39591(127.16.229.252:39591)] connecting to peer
20:03:40.855 [DEBUG - main] (Connection.java:748) [peer master-127.16.229.253:38725(127.16.229.253:38725)] connecting to peer
20:03:40.876 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.16.229.254:41755(127.16.229.254:41755)] Successfully connected to peer
20:03:40.876 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.16.229.253:38725(127.16.229.253:38725)] Successfully connected to peer
20:03:40.876 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.16.229.252:39591(127.16.229.252:39591)] Successfully connected to peer
20:03:40.974 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.16.229.253
20:03:40.975 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.16.229.254
20:03:40.976 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.16.229.252
20:03:40.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:40.997359 17716 heartbeater.cc:499] Master 127.16.229.252:39591 was elected leader, sending a full tablet report...
20:03:41.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.183672 17854 heartbeater.cc:499] Master 127.16.229.252:39591 was elected leader, sending a full tablet report...
20:03:41.191 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xb9941b88, L:/127.0.0.1:33974 - R:/127.16.229.252:39591] using SASL/PLAIN
20:03:41.191 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x66ee26f6, L:/127.0.0.1:58898 - R:/127.16.229.254:41755] using SASL/PLAIN
20:03:41.191 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0x0ad841b6, L:/127.0.0.1:42130 - R:/127.16.229.253:38725] using SASL/PLAIN
20:03:41.227 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
20:03:41.228 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=482), Traces: [0ms] refreshing cache from master, [30ms] Sub RPC ConnectToMaster: sending RPC to server master-127.16.229.254:41755, [104ms] Sub RPC ConnectToMaster: sending RPC to server master-127.16.229.252:39591, [106ms] Sub RPC ConnectToMaster: sending RPC to server master-127.16.229.253:38725, [455ms] Sub RPC ConnectToMaster: received response from server master-127.16.229.253:38725: OK, [455ms] Sub RPC ConnectToMaster: received response from server master-127.16.229.254:41755: OK, [456ms] Sub RPC ConnectToMaster: received response from server master-127.16.229.252:39591: OK, deferred=null) after lookup
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.282210 17490 catalog_manager.cc:2282] Servicing CreateTable request from {username='slave'} at 127.0.0.1:33974:
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1779134618342"
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
20:03:41.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
20:03:41.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
20:03:41.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
20:03:41.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
20:03:41.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
20:03:41.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:41.283306 17490 catalog_manager.cc:7058] 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-1779134618342 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
20:03:41.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.305675 17650 tablet_service.cc:1511] Processing CreateTablet for tablet c50f9858e2444d87978d0bdf329607ec (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779134618342 [id=e5aff812dbc840a28ad3d8a360c85a6f]), partition=RANGE (key) PARTITION UNBOUNDED
20:03:41.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.307232 17650 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c50f9858e2444d87978d0bdf329607ec. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:41.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.308130 17788 tablet_service.cc:1511] Processing CreateTablet for tablet c50f9858e2444d87978d0bdf329607ec (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779134618342 [id=e5aff812dbc840a28ad3d8a360c85a6f]), partition=RANGE (key) PARTITION UNBOUNDED
20:03:41.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.309446 17788 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c50f9858e2444d87978d0bdf329607ec. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:41.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.312778 17923 tablet_service.cc:1511] Processing CreateTablet for tablet c50f9858e2444d87978d0bdf329607ec (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779134618342 [id=e5aff812dbc840a28ad3d8a360c85a6f]), partition=RANGE (key) PARTITION UNBOUNDED
20:03:41.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.313854 17923 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c50f9858e2444d87978d0bdf329607ec. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:41.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.317777 18020 tablet_bootstrap.cc:492] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248: Bootstrap starting.
20:03:41.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.320475 18020 tablet_bootstrap.cc:654] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248: Neither blocks nor log segments found. Creating new log.
20:03:41.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.320665 18022 tablet_bootstrap.cc:492] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795: Bootstrap starting.
20:03:41.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.321631 18021 tablet_bootstrap.cc:492] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3: Bootstrap starting.
20:03:41.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.321905 18020 log.cc:826] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248: Log is configured to *not* fsync() on all Append() calls
20:03:41.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.323050 18022 tablet_bootstrap.cc:654] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795: Neither blocks nor log segments found. Creating new log.
20:03:41.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.323936 18021 tablet_bootstrap.cc:654] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3: Neither blocks nor log segments found. Creating new log.
20:03:41.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.324221 18020 tablet_bootstrap.cc:492] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248: No bootstrap required, opened a new log
20:03:41.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.324373 18022 log.cc:826] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795: Log is configured to *not* fsync() on all Append() calls
20:03:41.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.324481 18020 ts_tablet_manager.cc:1403] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248: Time spent bootstrapping tablet: real 0.007s	user 0.005s	sys 0.000s
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.324862 18021 log.cc:826] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3: Log is configured to *not* fsync() on all Append() calls
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.326129 18022 tablet_bootstrap.cc:492] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795: No bootstrap required, opened a new log
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.326282 18021 tablet_bootstrap.cc:492] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3: No bootstrap required, opened a new log
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.326305 18022 ts_tablet_manager.cc:1403] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795: Time spent bootstrapping tablet: real 0.006s	user 0.004s	sys 0.000s
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.326427 18021 ts_tablet_manager.cc:1403] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3: Time spent bootstrapping tablet: real 0.005s	user 0.002s	sys 0.001s
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.327579 18020 raft_consensus.cc:359] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.327800 18020 raft_consensus.cc:385] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.327839 18020 raft_consensus.cc:740] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 23071a6526834ab888695ec5316a5248, State: Initialized, Role: FOLLOWER
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.328238 18020 consensus_queue.cc:260] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [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: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.329092 18020 ts_tablet_manager.cc:1434] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248: Time spent starting tablet: real 0.005s	user 0.005s	sys 0.000s
20:03:41.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.330310 18022 raft_consensus.cc:359] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.330540 18022 raft_consensus.cc:385] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:41.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.330591 18022 raft_consensus.cc:740] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 7e257c72ab6f432a9202fd3d3c4a9795, State: Initialized, Role: FOLLOWER
20:03:41.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.330660 18021 raft_consensus.cc:359] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.330899 18021 raft_consensus.cc:385] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:41.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.330958 18021 raft_consensus.cc:740] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 95ecd370df594a07882aad7da369b0b3, State: Initialized, Role: FOLLOWER
20:03:41.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.331037 18022 consensus_queue.cc:260] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [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: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.331346 18021 consensus_queue.cc:260] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [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: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.331933 18022 ts_tablet_manager.cc:1434] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795: Time spent starting tablet: real 0.006s	user 0.006s	sys 0.000s
20:03:41.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.332046 18021 ts_tablet_manager.cc:1434] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3: Time spent starting tablet: real 0.005s	user 0.002s	sys 0.003s
20:03:41.332 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table e5aff812dbc840a28ad3d8a360c85a6f
20:03:41.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.332544 17989 heartbeater.cc:499] Master 127.16.229.252:39591 was elected leader, sending a full tablet report...
20:03:41.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:41.342568 17992 tablet.cc:2404] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:41.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.392581 18026 raft_consensus.cc:493] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
20:03:41.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.392800 18026 raft_consensus.cc:515] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.393987 18026 leader_election.cc:290] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 95ecd370df594a07882aad7da369b0b3 (127.16.229.194:44513), 7e257c72ab6f432a9202fd3d3c4a9795 (127.16.229.195:45329)
20:03:41.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.398998 17943 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c50f9858e2444d87978d0bdf329607ec" candidate_uuid: "23071a6526834ab888695ec5316a5248" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" is_pre_election: true
20:03:41.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.399003 17808 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c50f9858e2444d87978d0bdf329607ec" candidate_uuid: "23071a6526834ab888695ec5316a5248" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "95ecd370df594a07882aad7da369b0b3" is_pre_election: true
20:03:41.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.399276 17943 raft_consensus.cc:2468] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 23071a6526834ab888695ec5316a5248 in term 0.
20:03:41.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.399282 17808 raft_consensus.cc:2468] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 23071a6526834ab888695ec5316a5248 in term 0.
20:03:41.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.399735 17603 leader_election.cc:304] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [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: 23071a6526834ab888695ec5316a5248, 7e257c72ab6f432a9202fd3d3c4a9795; no voters: 
20:03:41.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.400071 18026 raft_consensus.cc:2804] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Leader pre-election won for term 1
20:03:41.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.400143 18026 raft_consensus.cc:493] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
20:03:41.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.400173 18026 raft_consensus.cc:3060] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 0 FOLLOWER]: Advancing to term 1
20:03:41.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.401055 18026 raft_consensus.cc:515] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.401608 18026 leader_election.cc:290] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [CANDIDATE]: Term 1 election: Requested vote from peers 95ecd370df594a07882aad7da369b0b3 (127.16.229.194:44513), 7e257c72ab6f432a9202fd3d3c4a9795 (127.16.229.195:45329)
20:03:41.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.402086 17808 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c50f9858e2444d87978d0bdf329607ec" candidate_uuid: "23071a6526834ab888695ec5316a5248" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "95ecd370df594a07882aad7da369b0b3"
20:03:41.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.402161 17943 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c50f9858e2444d87978d0bdf329607ec" candidate_uuid: "23071a6526834ab888695ec5316a5248" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "7e257c72ab6f432a9202fd3d3c4a9795"
20:03:41.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.402269 17943 raft_consensus.cc:3060] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [term 0 FOLLOWER]: Advancing to term 1
20:03:41.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.402264 17808 raft_consensus.cc:3060] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [term 0 FOLLOWER]: Advancing to term 1
20:03:41.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.403100 17943 raft_consensus.cc:2468] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 23071a6526834ab888695ec5316a5248 in term 1.
20:03:41.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.403321 17808 raft_consensus.cc:2468] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 23071a6526834ab888695ec5316a5248 in term 1.
20:03:41.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.403403 17603 leader_election.cc:304] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [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: 23071a6526834ab888695ec5316a5248, 7e257c72ab6f432a9202fd3d3c4a9795; no voters: 
20:03:41.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.403589 18026 raft_consensus.cc:2804] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 1 FOLLOWER]: Leader election won for term 1
20:03:41.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.403796 18026 raft_consensus.cc:697] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [term 1 LEADER]: Becoming Leader. State: Replica: 23071a6526834ab888695ec5316a5248, State: Running, Role: LEADER
20:03:41.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.404146 18026 consensus_queue.cc:237] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [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: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } }
20:03:41.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.407702 17490 catalog_manager.cc:5696] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 reported cstate change: term changed from 0 to 1, leader changed from <none> to 23071a6526834ab888695ec5316a5248 (127.16.229.193). New cstate: current_term: 1 leader_uuid: "23071a6526834ab888695ec5316a5248" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "23071a6526834ab888695ec5316a5248" member_type: VOTER last_known_addr { host: "127.16.229.193" port: 40315 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 } health_report { overall_health: UNKNOWN } } }
20:03:41.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:41.420470 17857 tablet.cc:2404] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:41.463 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet c50f9858e2444d87978d0bdf329607ec for table 'org.apache.kudu.client.TestHybridTime-1779134618342' with partition [<start>, <end>)
20:03:41.469 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=c50f9858e2444d87978d0bdf329607ec@[23071a6526834ab888695ec5316a5248(127.16.229.193:40315)[L],7e257c72ab6f432a9202fd3d3c4a9795(127.16.229.195:45329),95ecd370df594a07882aad7da369b0b3(127.16.229.194:44513)]}]
20:03:41.471 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=20), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.16.229.252:39591, [11ms] Sub RPC GetTableLocations: received response from server master-127.16.229.252:39591: OK, deferred=null) after lookup
20:03:41.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:41.483927 17719 tablet.cc:2404] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:41.496 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 23071a6526834ab888695ec5316a5248(127.16.229.193:40315)] connecting to peer
20:03:41.499 [DEBUG - kudu-nio-3] (Connection.java:757) [peer 23071a6526834ab888695ec5316a5248(127.16.229.193:40315)] Successfully connected to peer
20:03:41.564 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x595585a2, L:/127.0.0.1:57000 - R:/127.16.229.193:40315] using TOKEN/null
20:03:41.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.572781 17943 raft_consensus.cc:1275] T c50f9858e2444d87978d0bdf329607ec P 7e257c72ab6f432a9202fd3d3c4a9795 [term 1 FOLLOWER]: Refusing update from remote peer 23071a6526834ab888695ec5316a5248: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:41.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.572795 17808 raft_consensus.cc:1275] T c50f9858e2444d87978d0bdf329607ec P 95ecd370df594a07882aad7da369b0b3 [term 1 FOLLOWER]: Refusing update from remote peer 23071a6526834ab888695ec5316a5248: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:41.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.573694 18026 consensus_queue.cc:1048] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [LEADER]: Connected to new peer: Peer: permanent_uuid: "7e257c72ab6f432a9202fd3d3c4a9795" member_type: VOTER last_known_addr { host: "127.16.229.195" port: 45329 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:03:41.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.574210 18031 consensus_queue.cc:1048] T c50f9858e2444d87978d0bdf329607ec P 23071a6526834ab888695ec5316a5248 [LEADER]: Connected to new peer: Peer: permanent_uuid: "95ecd370df594a07882aad7da369b0b3" member_type: VOTER last_known_addr { host: "127.16.229.194" port: 44513 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:03:41.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.594131 18037 mvcc.cc:204] Tried to move back new op lower bound from 7287335409953107968 to 7287335409273774080. Current Snapshot: MvccSnapshot[applied={T|T < 7287335409953107968}]
20:03:41.629 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:41.641 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:41.649 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:41.654 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1317795497(state=PENDING, result=null, callback=<none>, errback=<none>)}
20:03:41.666 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:41.667 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@2085160548(state=PENDING, result=null, callback=<none>, errback=<none>)}
20:03:41.674 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:41.674 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@504555969(state=PENDING, result=null, callback=<none>, errback=<none>)}
20:03:41.684 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:41.728 [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-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.735 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7287335430433107969) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.740 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7287335430433107973) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.744 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7287335430433107977) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.748 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7287335430433107981) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.753 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7287335430433107985) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.758 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7287335430433107989) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.763 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7287335430433107990) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779134618342, tablet=c50f9858e2444d87978d0bdf329607ec, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:41.767 [DEBUG - main] (Connection.java:575) [peer master-127.16.229.252:39591(127.16.229.252:39591)] disconnecting while in state READY
20:03:41.768 [DEBUG - main] (Connection.java:575) [peer master-127.16.229.253:38725(127.16.229.253:38725)] disconnecting while in state READY
20:03:41.768 [DEBUG - main] (Connection.java:575) [peer master-127.16.229.254:41755(127.16.229.254:41755)] disconnecting while in state READY
20:03:41.770 [DEBUG - main] (Connection.java:575) [peer 23071a6526834ab888695ec5316a5248(127.16.229.193:40315)] disconnecting while in state READY
20:03:41.774 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.16.229.253:38725(127.16.229.253:38725)] handling channelInactive
20:03:41.775 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.16.229.253:38725(127.16.229.253:38725)] cleaning up while in state READY due to: connection closed
20:03:41.776 [DEBUG - kudu-nio-3] (Connection.java:239) [peer 23071a6526834ab888695ec5316a5248(127.16.229.193:40315)] handling channelInactive
20:03:41.776 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2704) Releasing all remaining resources
20:03:41.776 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.16.229.252:39591(127.16.229.252:39591)] handling channelInactive
20:03:41.777 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.16.229.252:39591(127.16.229.252:39591)] cleaning up while in state READY due to: connection closed
20:03:41.777 [DEBUG - kudu-nio-3] (Connection.java:709) [peer 23071a6526834ab888695ec5316a5248(127.16.229.193:40315)] cleaning up while in state READY due to: connection closed
20:03:41.782 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.16.229.254:41755(127.16.229.254:41755)] handling channelInactive
20:03:41.782 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.16.229.254:41755(127.16.229.254:41755)] cleaning up while in state READY due to: connection closed
20:03:41.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.783939 17303 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu with pid 17588
20:03:41.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.790594 17303 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu with pid 17726
20:03:41.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.797308 17303 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu with pid 17861
20:03:41.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.804450 17303 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu with pid 17315
20:03:41.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:41.812629 17475 connection.cc:582] client connection to 127.16.229.254:41755 recv error: Network error: recv error from unknown peer: Transport endpoint is not connected (error 107)
20:03:41.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.812752 17303 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu with pid 17384
20:03:41.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:41.812803 17475 proxy.cc:239] Call had error, refreshing address and retrying: Network error: recv error from unknown peer: Transport endpoint is not connected (error 107)
20:03:41.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 20:03:41.814304 17475 consensus_peers.cc:597] T 00000000000000000000000000000000 P 8ad901378a524b2d94f11a4f6bec9337 -> Peer ecfeebd6c3f34db09f5c9119576c44db (127.16.229.254:41755): Couldn't send request to peer ecfeebd6c3f34db09f5c9119576c44db. Status: Network error: Client connection negotiation failed: client connection to 127.16.229.254:41755: connect: Connection refused (error 111). This is attempt 1: this message will repeat every 5th retry.
20:03:41.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 20:03:41.821084 17303 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskfxOqvD/build/debug/bin/kudu with pid 17457
20:03:41.854 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T20:03:41Z chronyd exiting

Time: 3.686

OK (1 test)

