JUnit version 4.13.2
.14:26:19.462 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1779114379452
14:26:19.496 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
14:26:19.539 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
14:26:19.550 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskBFcbTT/test-tmp
14:26:19.588 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/release/bin
14:26:19.591 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskBFcbTT/java/build/dist-test/../../../build/release/bin/kudu, test, mini_cluster, --serialization=pb]
14:26:19.818 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

14:26:19.838 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:26:19.840 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

14:26:19.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.841529 29122 mini_oidc.cc:150] Starting JWKS server
14:26:19.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.842303 29122 webserver.cc:533] Webserver started at http://127.0.0.1:44463/ using document root <none> and password file <none>
14:26:19.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.842393 29122 mini_oidc.cc:186] Starting OIDC Discovery server
14:26:19.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.842548 29122 webserver.cc:533] Webserver started at http://127.0.0.1:41545/ using document root <none> and password file <none>
14:26:19.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:26:19Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
14:26:19.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:26:19Z Disabled control of system clock
14:26:19.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.865805 29122 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:19.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:19.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/wal
14:26:19.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data
14:26:19.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:26:19.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:26:19.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:26:19.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:19.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:19.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:19.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/logs
14:26:19.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data/info.pb
14:26:19.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:19.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:26:19.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:26:19.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:26:19.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:26:19.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:19.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:19.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:19.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:26:19.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:26:19.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:26:19.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:26:19.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.190:44777
14:26:19.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.190
14:26:19.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:19.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:19.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:19.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:19.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:19.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:26:19.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:19.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:26:19.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44463/jwks.json with env {}
14:26:19.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.949204 29133 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:26:19.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.949407 29133 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:26:19.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.949429 29133 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:26:19.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.951357 29133 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:26:19.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.951426 29133 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:26:19.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.951445 29133 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:26:19.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.951463 29133 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:26:19.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.951532 29133 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:26:19.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.951550 29133 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:44463/jwks.json
14:26:19.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.953650 29133 master_runner.cc:387] Master server non-default flags:
14:26:19.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:19.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:19.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:19.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data
14:26:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/wal
14:26:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:26:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:26:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.190:44777
14:26:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:26:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:26:19.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:26:19.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44463/jwks.json
14:26:19.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:19.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:19.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data/info.pb
14:26:19.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.190
14:26:19.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:19.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:26:19.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29133
14:26:19.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:19.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:26:19.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:26:19.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:19.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/logs
14:26:19.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:19.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:26:19.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:26:19.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:26:19.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:26:19.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:26:19.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
14:26:19.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:45 UTC on e7f111948823
14:26:19.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15852
14:26:19.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.953881 29133 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:26:19.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.954162 29133 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:26:19.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.957870 29138 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:19.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.957895 29141 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:19.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.958207 29133 server_base.cc:1061] running on GCE node
14:26:19.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:19.957875 29139 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:19.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.958491 29133 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:26:19.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.958806 29133 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:26:19.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.959980 29133 hybrid_clock.cc:648] HybridClock initialized: now 1779114379959943 us; error 49 us; skew 500 ppm
14:26:19.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.961493 29133 webserver.cc:533] Webserver started at http://127.28.112.190:44435/ using document root <none> and password file <none>
14:26:19.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.961701 29133 fs_manager.cc:362] Metadata directory not provided
14:26:19.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.961758 29133 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:26:19.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.961861 29133 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:26:19.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.962985 29133 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data/instance:
14:26:19.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e"
14:26:19.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:19 on dist-test-slave-r1vp"
14:26:19.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.963372 29133 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/wal/instance:
14:26:19.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e"
14:26:19.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:19 on dist-test-slave-r1vp"
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.966178 29133 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.000s	sys 0.003s
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.966961 29147 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.967190 29133 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.967264 29133 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data,/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/wal
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e"
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:19 on dist-test-slave-r1vp"
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.967331 29133 fs_report.cc:389] FS layout report
14:26:19.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:26:19.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/wal
14:26:19.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/wal
14:26:19.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data/data
14:26:19.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:26:19.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:26:19.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:26:19.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:26:19.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:26:19.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:26:19.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:26:19.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:26:19.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:26:19.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:26:19.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:26:20.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:19.999773 29133 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:26:20.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.000120 29133 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:26:20.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.000237 29133 kserver.cc:163] Server-wide thread pool size limit: 3276
14:26:20.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.005214 29133 rpc_server.cc:307] RPC server started. Bound to: 127.28.112.190:44777
14:26:20.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.005381 29199 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.112.190:44777 every 8 connection(s)
14:26:20.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.005609 29133 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/data/info.pb
14:26:20.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.005896 29122 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskBFcbTT/build/release/bin/kudu as pid 29133
14:26:20.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.005997 29122 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-0/wal/instance
14:26:20.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.006330 29200 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:26:20.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.006816 29122 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/wal
14:26:20.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data
14:26:20.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:26:20.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:26:20.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:26:20.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/logs
14:26:20.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data/info.pb
14:26:20.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:26:20.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:26:20.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:26:20.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:26:20.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:26:20.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:26:20.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:26:20.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:26:20.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.189:42609
14:26:20.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.189
14:26:20.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:20.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:26:20.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:26:20.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44463/jwks.json with env {}
14:26:20.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.008170 29200 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.015105 29200 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.015689 29148 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.28.112.189:42609: connect: Connection refused (error 111)
14:26:20.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.016176 29200 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.28.112.189:42609: Network error: Client connection negotiation failed: client connection to 127.28.112.189:42609: connect: Connection refused (error 111)
14:26:20.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.065445 29200 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } attempt: 1
14:26:20.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.066823 29200 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.28.112.189:42609: Network error: Client connection negotiation failed: client connection to 127.28.112.189:42609: connect: Connection refused (error 111)
14:26:20.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.092955 29202 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:26:20.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.093273 29202 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:26:20.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.093313 29202 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:26:20.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.095402 29202 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:26:20.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.095487 29202 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:26:20.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.095510 29202 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:26:20.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.095530 29202 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:26:20.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.095551 29202 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:26:20.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.095566 29202 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:44463/jwks.json
14:26:20.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.097702 29202 master_runner.cc:387] Master server non-default flags:
14:26:20.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:20.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data
14:26:20.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/wal
14:26:20.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:26:20.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:26:20.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.189:42609
14:26:20.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:26:20.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:26:20.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:26:20.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44463/jwks.json
14:26:20.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data/info.pb
14:26:20.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.189
14:26:20.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:26:20.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29202
14:26:20.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:26:20.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:26:20.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/logs
14:26:20.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:26:20.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:26:20.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:45 UTC on e7f111948823
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15852
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.097995 29202 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.098271 29202 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.101627 29211 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.101778 29212 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.101583 29214 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.101884 29202 server_base.cc:1061] running on GCE node
14:26:20.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.102255 29202 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:26:20.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.102528 29202 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:26:20.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.105916 29202 hybrid_clock.cc:648] HybridClock initialized: now 1779114380105872 us; error 57 us; skew 500 ppm
14:26:20.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.107412 29202 webserver.cc:533] Webserver started at http://127.28.112.189:44157/ using document root <none> and password file <none>
14:26:20.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.107666 29202 fs_manager.cc:362] Metadata directory not provided
14:26:20.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.107728 29202 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:26:20.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.107834 29202 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:26:20.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.108841 29202 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data/instance:
14:26:20.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "473b9d0cb1814d23a24210ef6c390ee7"
14:26:20.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.109243 29202 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/wal/instance:
14:26:20.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "473b9d0cb1814d23a24210ef6c390ee7"
14:26:20.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.110908 29202 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
14:26:20.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.111876 29220 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:26:20.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.112221 29202 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
14:26:20.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.112363 29202 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data,/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/wal
14:26:20.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "473b9d0cb1814d23a24210ef6c390ee7"
14:26:20.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.112459 29202 fs_report.cc:389] FS layout report
14:26:20.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:26:20.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/wal
14:26:20.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/wal
14:26:20.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data/data
14:26:20.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:26:20.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:26:20.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:26:20.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:26:20.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:26:20.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:26:20.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:26:20.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:26:20.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:26:20.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:26:20.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:26:20.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.135018 29200 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } attempt: 2
14:26:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.135787 29200 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.28.112.189:42609: Network error: Client connection negotiation failed: client connection to 127.28.112.189:42609: connect: Connection refused (error 111)
14:26:20.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.148330 29202 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:26:20.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.148748 29202 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:26:20.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.148895 29202 kserver.cc:163] Server-wide thread pool size limit: 3276
14:26:20.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.153358 29202 rpc_server.cc:307] RPC server started. Bound to: 127.28.112.189:42609
14:26:20.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.153411 29272 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.112.189:42609 every 8 connection(s)
14:26:20.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.153934 29202 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/data/info.pb
14:26:20.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.154214 29122 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskBFcbTT/build/release/bin/kudu as pid 29202
14:26:20.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.154409 29122 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-1/wal/instance
14:26:20.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.154817 29122 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/wal
14:26:20.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data
14:26:20.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:26:20.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/logs
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data/info.pb
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:26:20.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:26:20.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:26:20.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:26:20.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:26:20.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:26:20.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:26:20.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:26:20.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.188:33963
14:26:20.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.188
14:26:20.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:20.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:26:20.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:26:20.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44463/jwks.json with env {}
14:26:20.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.156286 29273 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:26:20.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.158468 29273 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.165592 29273 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.170992 29273 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.171383 29221 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.28.112.188:33963: connect: Connection refused (error 111)
14:26:20.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.171826 29273 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.28.112.188:33963: Network error: Client connection negotiation failed: client connection to 127.28.112.188:33963: connect: Connection refused (error 111)
14:26:20.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.221030 29273 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } attempt: 1
14:26:20.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.222081 29273 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.28.112.188:33963: Network error: Client connection negotiation failed: client connection to 127.28.112.188:33963: connect: Connection refused (error 111)
14:26:20.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.226965 29200 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } attempt: 3
14:26:20.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.231127 29200 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.232069 29200 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.28.112.188:33963: Network error: Client connection negotiation failed: client connection to 127.28.112.188:33963: connect: Connection refused (error 111)
14:26:20.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.241245 29275 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:26:20.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.241439 29275 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:26:20.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.241470 29275 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:26:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.243307 29275 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:26:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.243384 29275 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:26:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.243407 29275 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:26:20.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.243424 29275 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:26:20.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.243440 29275 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:26:20.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.243456 29275 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:44463/jwks.json
14:26:20.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.245496 29275 master_runner.cc:387] Master server non-default flags:
14:26:20.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/wal
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:26:20.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.188:33963
14:26:20.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:26:20.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:26:20.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:26:20.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44463/jwks.json
14:26:20.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data/info.pb
14:26:20.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.188
14:26:20.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:26:20.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29275
14:26:20.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:26:20.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:26:20.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/logs
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:26:20.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
14:26:20.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:45 UTC on e7f111948823
14:26:20.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15852
14:26:20.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.245798 29275 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:26:20.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.246125 29275 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:26:20.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.250083 29284 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.250053 29283 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.250198 29275 server_base.cc:1061] running on GCE node
14:26:20.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.250048 29286 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.251958 29275 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:26:20.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.252283 29275 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:26:20.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.253475 29275 hybrid_clock.cc:648] HybridClock initialized: now 1779114380253455 us; error 42 us; skew 500 ppm
14:26:20.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.254945 29275 webserver.cc:533] Webserver started at http://127.28.112.188:34617/ using document root <none> and password file <none>
14:26:20.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.255191 29275 fs_manager.cc:362] Metadata directory not provided
14:26:20.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.255266 29275 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:26:20.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.255381 29275 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:26:20.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.256414 29275 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data/instance:
14:26:20.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb"
14:26:20.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.256793 29275 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/wal/instance:
14:26:20.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb"
14:26:20.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.258661 29275 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.000s	sys 0.004s
14:26:20.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.259752 29292 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.260018 29275 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.260090 29275 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data,/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/wal
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb"
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.260149 29275 fs_report.cc:389] FS layout report
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/wal
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/wal
14:26:20.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data/data
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:26:20.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:26:20.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.263262 29200 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } attempt: 1
14:26:20.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.264238 29200 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.28.112.188:33963: Network error: Client connection negotiation failed: client connection to 127.28.112.188:33963: connect: Connection refused (error 111)
14:26:20.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.280459 29275 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:26:20.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.280781 29275 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:26:20.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.280871 29275 kserver.cc:163] Server-wide thread pool size limit: 3276
14:26:20.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.285234 29275 rpc_server.cc:307] RPC server started. Bound to: 127.28.112.188:33963
14:26:20.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.285478 29344 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.112.188:33963 every 8 connection(s)
14:26:20.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.285580 29275 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/data/info.pb
14:26:20.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.286239 29345 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:26:20.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.287621 29345 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.290366 29273 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } attempt: 2
14:26:20.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.290618 29122 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskBFcbTT/build/release/bin/kudu as pid 29275
14:26:20.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.290693 29122 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/master-2/wal/instance
14:26:20.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.293867 29345 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.298100 29345 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } has no permanent_uuid. Determining permanent_uuid...
14:26:20.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.298197 29273 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: Bootstrap starting.
14:26:20.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.298957 29273 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: Neither blocks nor log segments found. Creating new log.
14:26:20.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.299261 29273 log.cc:826] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: Log is configured to *not* fsync() on all Append() calls
14:26:20.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.302080 29273 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: No bootstrap required, opened a new log
14:26:20.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.303399 29273 raft_consensus.cc:359] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.303494 29273 raft_consensus.cc:385] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:26:20.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.303514 29273 raft_consensus.cc:740] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 473b9d0cb1814d23a24210ef6c390ee7, State: Initialized, Role: FOLLOWER
14:26:20.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.303654 29273 consensus_queue.cc:260] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [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: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.304255 29273 sys_catalog.cc:565] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [sys.catalog]: configured and running, proceeding with master startup.
14:26:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.305208 29345 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb: Bootstrap starting.
14:26:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.305652 29355 sys_catalog.cc:455] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [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: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.305737 29355 sys_catalog.cc:458] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [sys.catalog]: This master's current role is: FOLLOWER
14:26:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.305987 29345 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb: Neither blocks nor log segments found. Creating new log.
14:26:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.306279 29345 log.cc:826] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb: Log is configured to *not* fsync() on all Append() calls
14:26:20.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.307018 29345 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb: No bootstrap required, opened a new log
14:26:20.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.307083 29366 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:26:20.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.307126 29366 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:26:20.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.306607 29202 master_runner.cc:187] Error getting master registration for 127.28.112.190:44777: OK, instance_id { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" instance_seqno: 1779114380003892 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:26:20.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.308274 29202 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:26:20.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.309063 29345 raft_consensus.cc:359] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.309249 29345 raft_consensus.cc:385] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:26:20.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.309273 29345 raft_consensus.cc:740] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1321ed0e3c3c40dd967ac31dcaecbbcb, State: Initialized, Role: FOLLOWER
14:26:20.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.309370 29345 consensus_queue.cc:260] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [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: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.309643 29345 sys_catalog.cc:565] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: configured and running, proceeding with master startup.
14:26:20.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.310189 29369 sys_catalog.cc:455] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [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: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.310520 29369 sys_catalog.cc:458] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: This master's current role is: FOLLOWER
14:26:20.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.311714 29380 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:26:20.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.312108 29380 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:26:20.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.311962 29275 master_runner.cc:187] Error getting master registration for 127.28.112.190:44777: OK, instance_id { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" instance_seqno: 1779114380003892 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:26:20.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.313738 29275 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:26:20.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.339437 29200 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } attempt: 2
14:26:20.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.344902 29200 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: Bootstrap starting.
14:26:20.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.345801 29200 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: Neither blocks nor log segments found. Creating new log.
14:26:20.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.346484 29200 log.cc:826] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: Log is configured to *not* fsync() on all Append() calls
14:26:20.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.347528 29200 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: No bootstrap required, opened a new log
14:26:20.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.349716 29200 raft_consensus.cc:359] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.349876 29200 raft_consensus.cc:385] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:26:20.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.349910 29200 raft_consensus.cc:740] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: bc15a8f7a2b44eb0b5bdc753c826dd6e, State: Initialized, Role: FOLLOWER
14:26:20.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.350016 29200 consensus_queue.cc:260] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [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: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.350385 29200 sys_catalog.cc:565] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [sys.catalog]: configured and running, proceeding with master startup.
14:26:20.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.352646 29383 sys_catalog.cc:455] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [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: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.353189 29383 sys_catalog.cc:458] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [sys.catalog]: This master's current role is: FOLLOWER
14:26:20.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.353519 29394 catalog_manager.cc:1594] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:26:20.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.353582 29394 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:26:20.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.355755 29133 master_runner.cc:264] No leader master found from master bc15a8f7a2b44eb0b5bdc753c826dd6e
14:26:20.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.355808 29133 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:26:20.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.399832 29226 tablet.cc:2404] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:26:20.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.441481 29369 raft_consensus.cc:493] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:26:20.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.441717 29369 raft_consensus.cc:515] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.441977 29369 leader_election.cc:290] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers bc15a8f7a2b44eb0b5bdc753c826dd6e (127.28.112.190:44777), 473b9d0cb1814d23a24210ef6c390ee7 (127.28.112.189:42609)
14:26:20.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.442366 29247 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "473b9d0cb1814d23a24210ef6c390ee7" is_pre_election: true
14:26:20.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.442529 29247 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 1321ed0e3c3c40dd967ac31dcaecbbcb in term 0.
14:26:20.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.442775 29293 leader_election.cc:304] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [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: 1321ed0e3c3c40dd967ac31dcaecbbcb, 473b9d0cb1814d23a24210ef6c390ee7; no voters: 
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.442898 29369 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Leader pre-election won for term 1
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.442932 29369 raft_consensus.cc:493] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.442955 29369 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 0 FOLLOWER]: Advancing to term 1
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.443774 29369 raft_consensus.cc:515] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.443921 29369 leader_election.cc:290] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [CANDIDATE]: Term 1 election: Requested vote from peers bc15a8f7a2b44eb0b5bdc753c826dd6e (127.28.112.190:44777), 473b9d0cb1814d23a24210ef6c390ee7 (127.28.112.189:42609)
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.444164 29247 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "473b9d0cb1814d23a24210ef6c390ee7"
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.444231 29247 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [term 0 FOLLOWER]: Advancing to term 1
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.445036 29247 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 1321ed0e3c3c40dd967ac31dcaecbbcb in term 1.
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.445385 29174 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" is_pre_election: true
14:26:20.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.445511 29174 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 1321ed0e3c3c40dd967ac31dcaecbbcb in term 0.
14:26:20.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.445629 29173 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e"
14:26:20.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.445669 29173 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [term 0 FOLLOWER]: Advancing to term 1
14:26:20.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.446496 29173 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 1321ed0e3c3c40dd967ac31dcaecbbcb in term 1.
14:26:20.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.446738 29293 leader_election.cc:304] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [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: 1321ed0e3c3c40dd967ac31dcaecbbcb, bc15a8f7a2b44eb0b5bdc753c826dd6e; no voters: 
14:26:20.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.449813 29369 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 1 FOLLOWER]: Leader election won for term 1
14:26:20.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.449993 29369 raft_consensus.cc:697] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [term 1 LEADER]: Becoming Leader. State: Replica: 1321ed0e3c3c40dd967ac31dcaecbbcb, State: Running, Role: LEADER
14:26:20.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.450079 29369 consensus_queue.cc:237] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [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: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } }
14:26:20.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.450438 29369 sys_catalog.cc:455] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: SysCatalogTable state changed. Reason: New leader 1321ed0e3c3c40dd967ac31dcaecbbcb. Latest consensus state: current_term: 1 leader_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.450548 29369 sys_catalog.cc:458] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: This master's current role is: LEADER
14:26:20.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.452466 29398 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
14:26:20.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.452982 29398 catalog_manager.cc:1520] Initializing Kudu cluster ID...
14:26:20.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.462142 29247 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [term 1 FOLLOWER]: Refusing update from remote peer 1321ed0e3c3c40dd967ac31dcaecbbcb: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:26:20.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.463116 29369 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [LEADER]: Connected to new peer: Peer: permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:26:20.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.464365 29355 sys_catalog.cc:455] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 1321ed0e3c3c40dd967ac31dcaecbbcb. Latest consensus state: current_term: 1 leader_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.464452 29355 sys_catalog.cc:458] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [sys.catalog]: This master's current role is: FOLLOWER
14:26:20.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.466132 29173 raft_consensus.cc:1275] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [term 1 FOLLOWER]: Refusing update from remote peer 1321ed0e3c3c40dd967ac31dcaecbbcb: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:26:20.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.466420 29369 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [LEADER]: Connected to new peer: Peer: permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:26:20.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.467904 29383 sys_catalog.cc:455] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 1321ed0e3c3c40dd967ac31dcaecbbcb. Latest consensus state: current_term: 1 leader_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.467941 29355 sys_catalog.cc:455] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.468020 29383 sys_catalog.cc:458] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [sys.catalog]: This master's current role is: FOLLOWER
14:26:20.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.468030 29355 sys_catalog.cc:458] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7 [sys.catalog]: This master's current role is: FOLLOWER
14:26:20.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.468346 29400 mvcc.cc:204] Tried to move back new op lower bound from 7287252502369701888 to 7287252502324174848. Current Snapshot: MvccSnapshot[applied={T|T < 7287252502369701888}]
14:26:20.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.469528 29399 mvcc.cc:204] Tried to move back new op lower bound from 7287252502369701888 to 7287252502324174848. Current Snapshot: MvccSnapshot[applied={T|T < 7287252502369701888}]
14:26:20.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.469856 29369 sys_catalog.cc:455] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.469945 29369 sys_catalog.cc:458] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: This master's current role is: LEADER
14:26:20.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.470638 29402 sys_catalog.cc:455] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.470955 29402 sys_catalog.cc:458] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb [sys.catalog]: This master's current role is: LEADER
14:26:20.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.470953 29383 sys_catalog.cc:455] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "bc15a8f7a2b44eb0b5bdc753c826dd6e" member_type: VOTER last_known_addr { host: "127.28.112.190" port: 44777 } } peers { permanent_uuid: "473b9d0cb1814d23a24210ef6c390ee7" member_type: VOTER last_known_addr { host: "127.28.112.189" port: 42609 } } peers { permanent_uuid: "1321ed0e3c3c40dd967ac31dcaecbbcb" member_type: VOTER last_known_addr { host: "127.28.112.188" port: 33963 } } }
14:26:20.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.471021 29383 sys_catalog.cc:458] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e [sys.catalog]: This master's current role is: FOLLOWER
14:26:20.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.471158 29404 mvcc.cc:204] Tried to move back new op lower bound from 7287252502369701888 to 7287252502324174848. Current Snapshot: MvccSnapshot[applied={T|T < 7287252502369701888}]
14:26:20.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.470712 29398 catalog_manager.cc:1383] Generated new cluster ID: efb07dfebf044f58b9ffbf8b55363834
14:26:20.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.471659 29398 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
14:26:20.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.524863 29398 catalog_manager.cc:1406] Generated new certificate authority record
14:26:20.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.525732 29398 catalog_manager.cc:1540] Loading token signing keys...
14:26:20.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.532421 29398 catalog_manager.cc:6070] T 00000000000000000000000000000000 P 1321ed0e3c3c40dd967ac31dcaecbbcb: Generated new TSK 0
14:26:20.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.533207 29398 catalog_manager.cc:1550] Initializing in-progress tserver states...
14:26:20.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.550460 29122 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/wal
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/logs
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data/info.pb
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.129:0
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.112.129
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.129
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:26:20.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.635413 29408 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:26:20.636 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.635701 29408 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:26:20.636 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.635735 29408 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:26:20.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.637588 29408 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:26:20.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.637683 29408 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.112.129
14:26:20.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.639544 29408 tablet_server_runner.cc:78] Tablet server non-default flags:
14:26:20.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:20.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data
14:26:20.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/wal
14:26:20.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.129:0
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data/info.pb
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.129
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29408
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.112.129
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/logs
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:26:20.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:26:20.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
14:26:20.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:45 UTC on e7f111948823
14:26:20.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15852
14:26:20.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.639881 29408 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:26:20.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.640168 29408 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:26:20.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.645459 29414 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.645468 29413 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.645926 29408 server_base.cc:1061] running on GCE node
14:26:20.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.645551 29416 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.646502 29408 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:26:20.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.646749 29408 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:26:20.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.648006 29408 hybrid_clock.cc:648] HybridClock initialized: now 1779114380647979 us; error 36 us; skew 500 ppm
14:26:20.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.649394 29408 webserver.cc:533] Webserver started at http://127.28.112.129:40391/ using document root <none> and password file <none>
14:26:20.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.649672 29408 fs_manager.cc:362] Metadata directory not provided
14:26:20.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.649744 29408 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:26:20.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.649865 29408 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:26:20.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.651002 29408 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data/instance:
14:26:20.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a03ece0c28664ad38b1e2c72e7090eeb"
14:26:20.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.651423 29408 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/wal/instance:
14:26:20.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a03ece0c28664ad38b1e2c72e7090eeb"
14:26:20.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.653208 29408 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.000s	sys 0.003s
14:26:20.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.654194 29422 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:26:20.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.654488 29408 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
14:26:20.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.654624 29408 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data,/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/wal
14:26:20.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a03ece0c28664ad38b1e2c72e7090eeb"
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.654706 29408 fs_report.cc:389] FS layout report
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/wal
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/wal
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data/data
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:26:20.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:26:20.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:26:20.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:26:20.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:26:20.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:26:20.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:26:20.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:26:20.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.675846 29408 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:26:20.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.676256 29408 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:26:20.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.676388 29408 kserver.cc:163] Server-wide thread pool size limit: 3276
14:26:20.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.676642 29408 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:26:20.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.677052 29408 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:26:20.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.677124 29408 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:26:20.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.677160 29408 ts_tablet_manager.cc:616] Registered 0 tablets
14:26:20.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.677178 29408 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:26:20.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.683764 29408 rpc_server.cc:307] RPC server started. Bound to: 127.28.112.129:40055
14:26:20.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.683799 29535 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.112.129:40055 every 8 connection(s)
14:26:20.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.684126 29408 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/data/info.pb
14:26:20.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.686522 29122 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskBFcbTT/build/release/bin/kudu as pid 29408
14:26:20.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.686623 29122 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-0/wal/instance
14:26:20.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.688278 29122 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/wal
14:26:20.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data
14:26:20.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/logs
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data/info.pb
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:26:20.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.130:0
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.112.130
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.130
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.690079 29536 heartbeater.cc:344] Connected to a master server at 127.28.112.188:33963
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.690181 29536 heartbeater.cc:461] Registering TS with master...
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.690410 29536 heartbeater.cc:507] Master 127.28.112.188:33963 requested a full tablet report, sending...
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.690589 29538 heartbeater.cc:344] Connected to a master server at 127.28.112.189:42609
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.690644 29538 heartbeater.cc:461] Registering TS with master...
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.690754 29538 heartbeater.cc:507] Master 127.28.112.189:42609 requested a full tablet report, sending...
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.690884 29308 ts_manager.cc:194] Registered new tserver with Master: a03ece0c28664ad38b1e2c72e7090eeb (127.28.112.129:40055)
14:26:20.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.691725 29308 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.28.112.129:47205
14:26:20.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.692255 29237 ts_manager.cc:194] Registered new tserver with Master: a03ece0c28664ad38b1e2c72e7090eeb (127.28.112.129:40055)
14:26:20.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.694134 29537 heartbeater.cc:344] Connected to a master server at 127.28.112.190:44777
14:26:20.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.694206 29537 heartbeater.cc:461] Registering TS with master...
14:26:20.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.694290 29537 heartbeater.cc:507] Master 127.28.112.190:44777 requested a full tablet report, sending...
14:26:20.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.694741 29164 ts_manager.cc:194] Registered new tserver with Master: a03ece0c28664ad38b1e2c72e7090eeb (127.28.112.129:40055)
14:26:20.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.774824 29543 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:26:20.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.775028 29543 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:26:20.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.775050 29543 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:26:20.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.777073 29543 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:26:20.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.777253 29543 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.112.130
14:26:20.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.779459 29543 tablet_server_runner.cc:78] Tablet server non-default flags:
14:26:20.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/wal
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.130:0
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data/info.pb
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.130
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29543
14:26:20.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.112.130
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/logs
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:26:20.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
14:26:20.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:45 UTC on e7f111948823
14:26:20.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15852
14:26:20.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.779801 29543 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:26:20.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.780092 29543 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:26:20.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.783357 29548 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.783437 29551 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.783448 29549 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.784035 29543 server_base.cc:1061] running on GCE node
14:26:20.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.784211 29543 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:26:20.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.784639 29543 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:26:20.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.785847 29543 hybrid_clock.cc:648] HybridClock initialized: now 1779114380785826 us; error 38 us; skew 500 ppm
14:26:20.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.787402 29543 webserver.cc:533] Webserver started at http://127.28.112.130:41957/ using document root <none> and password file <none>
14:26:20.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.787671 29543 fs_manager.cc:362] Metadata directory not provided
14:26:20.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.787734 29543 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:26:20.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.787843 29543 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:26:20.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.788811 29543 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data/instance:
14:26:20.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3206628999d14b4ba8b0e3be3678d0e0"
14:26:20.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.789239 29543 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/wal/instance:
14:26:20.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3206628999d14b4ba8b0e3be3678d0e0"
14:26:20.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.790831 29543 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.001s	sys 0.000s
14:26:20.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.791805 29557 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:26:20.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.792088 29543 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
14:26:20.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.792163 29543 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data,/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/wal
14:26:20.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3206628999d14b4ba8b0e3be3678d0e0"
14:26:20.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.792377 29543 fs_report.cc:389] FS layout report
14:26:20.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/wal
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/wal
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data/data
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:26:20.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:26:20.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:26:20.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:26:20.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.803903 29543 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:26:20.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.804320 29543 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:26:20.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.804461 29543 kserver.cc:163] Server-wide thread pool size limit: 3276
14:26:20.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.804723 29543 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:26:20.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.805220 29543 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:26:20.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.805265 29543 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:26:20.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.805300 29543 ts_tablet_manager.cc:616] Registered 0 tablets
14:26:20.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.805320 29543 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:26:20.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.812974 29543 rpc_server.cc:307] RPC server started. Bound to: 127.28.112.130:42385
14:26:20.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.813014 29670 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.112.130:42385 every 8 connection(s)
14:26:20.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.813393 29543 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/data/info.pb
14:26:20.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.814983 29122 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskBFcbTT/build/release/bin/kudu as pid 29543
14:26:20.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.815084 29122 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-1/wal/instance
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.816605 29122 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBFcbTT/build/release/bin/kudu
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/wal
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/logs
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data/info.pb
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.131:0
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.112.131
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.131
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:26:20.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.820919 29671 heartbeater.cc:344] Connected to a master server at 127.28.112.188:33963
14:26:20.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.821063 29671 heartbeater.cc:461] Registering TS with master...
14:26:20.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.821406 29671 heartbeater.cc:507] Master 127.28.112.188:33963 requested a full tablet report, sending...
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.821712 29673 heartbeater.cc:344] Connected to a master server at 127.28.112.189:42609
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.821794 29673 heartbeater.cc:461] Registering TS with master...
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.821882 29673 heartbeater.cc:507] Master 127.28.112.189:42609 requested a full tablet report, sending...
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.821869 29308 ts_manager.cc:194] Registered new tserver with Master: 3206628999d14b4ba8b0e3be3678d0e0 (127.28.112.130:42385)
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.822068 29672 heartbeater.cc:344] Connected to a master server at 127.28.112.190:44777
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.822122 29672 heartbeater.cc:461] Registering TS with master...
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.822227 29672 heartbeater.cc:507] Master 127.28.112.190:44777 requested a full tablet report, sending...
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.822361 29308 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.28.112.130:56345
14:26:20.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.822487 29237 ts_manager.cc:194] Registered new tserver with Master: 3206628999d14b4ba8b0e3be3678d0e0 (127.28.112.130:42385)
14:26:20.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.822913 29164 ts_manager.cc:194] Registered new tserver with Master: 3206628999d14b4ba8b0e3be3678d0e0 (127.28.112.130:42385)
14:26:20.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.899295 29678 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:26:20.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.899513 29678 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:26:20.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.899549 29678 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:26:20.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.901355 29678 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:26:20.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.901434 29678 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.112.131
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.903015 29678 tablet_server_runner.cc:78] Tablet server non-default flags:
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.112.148:41485
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/wal
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.112.131:0
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:26:20.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data/info.pb
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.112.131
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.112.190:44777,127.28.112.189:42609,127.28.112.188:33963
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29678
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.112.131
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/logs
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:26:20.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:26:20.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:26:20.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
14:26:20.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:45 UTC on e7f111948823
14:26:20.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15852
14:26:20.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.903290 29678 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:26:20.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.903553 29678 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:26:20.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.906692 29684 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.906708 29683 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.906870 29678 server_base.cc:1061] running on GCE node
14:26:20.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:20.906733 29686 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:26:20.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.907140 29678 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:26:20.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.907413 29678 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:26:20.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.908602 29678 hybrid_clock.cc:648] HybridClock initialized: now 1779114380908567 us; error 48 us; skew 500 ppm
14:26:20.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.909843 29678 webserver.cc:533] Webserver started at http://127.28.112.131:40897/ using document root <none> and password file <none>
14:26:20.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.910065 29678 fs_manager.cc:362] Metadata directory not provided
14:26:20.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.910123 29678 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:26:20.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.910241 29678 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:26:20.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.911224 29678 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data/instance:
14:26:20.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "65ec72d3d774458c96c61cd10b6fb9b2"
14:26:20.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.911617 29678 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/wal/instance:
14:26:20.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "65ec72d3d774458c96c61cd10b6fb9b2"
14:26:20.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.913002 29678 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.001s	sys 0.002s
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.913926 29692 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.914158 29678 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.914227 29678 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data,/tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/wal
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "65ec72d3d774458c96c61cd10b6fb9b2"
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:26:20 on dist-test-slave-r1vp"
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.914367 29678 fs_report.cc:389] FS layout report
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:26:20.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/wal
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/wal
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data/data
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:26:20.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:26:20.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.926185 29678 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:26:20.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.926607 29678 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:26:20.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.926755 29678 kserver.cc:163] Server-wide thread pool size limit: 3276
14:26:20.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.927047 29678 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:26:20.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.927474 29678 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:26:20.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.927542 29678 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:26:20.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.927585 29678 ts_tablet_manager.cc:616] Registered 0 tablets
14:26:20.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.927608 29678 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:26:20.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.934650 29678 rpc_server.cc:307] RPC server started. Bound to: 127.28.112.131:42521
14:26:20.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.934734 29805 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.112.131:42521 every 8 connection(s)
14:26:20.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.935055 29678 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/data/info.pb
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.940876 29808 heartbeater.cc:344] Connected to a master server at 127.28.112.189:42609
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.940877 29806 heartbeater.cc:344] Connected to a master server at 127.28.112.188:33963
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941010 29808 heartbeater.cc:461] Registering TS with master...
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941012 29806 heartbeater.cc:461] Registering TS with master...
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941248 29806 heartbeater.cc:507] Master 127.28.112.188:33963 requested a full tablet report, sending...
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941252 29808 heartbeater.cc:507] Master 127.28.112.189:42609 requested a full tablet report, sending...
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941738 29308 ts_manager.cc:194] Registered new tserver with Master: 65ec72d3d774458c96c61cd10b6fb9b2 (127.28.112.131:42521)
14:26:20.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941776 29807 heartbeater.cc:344] Connected to a master server at 127.28.112.190:44777
14:26:20.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941829 29807 heartbeater.cc:461] Registering TS with master...
14:26:20.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.941916 29807 heartbeater.cc:507] Master 127.28.112.190:44777 requested a full tablet report, sending...
14:26:20.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.942131 29308 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.28.112.131:56205
14:26:20.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.942234 29164 ts_manager.cc:194] Registered new tserver with Master: 65ec72d3d774458c96c61cd10b6fb9b2 (127.28.112.131:42521)
14:26:20.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.942338 29237 ts_manager.cc:194] Registered new tserver with Master: 65ec72d3d774458c96c61cd10b6fb9b2 (127.28.112.131:42521)
14:26:20.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.944614 29122 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskBFcbTT/build/release/bin/kudu as pid 29678
14:26:20.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.944692 29122 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskBFcbTT/test-tmp/mini-kudu-cluster14762791826366039551/ts-2/wal/instance
14:26:20.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:20.947880 29122 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
14:26:20.949 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:26:20.950 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

14:26:20.961 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.28.112.190"
      port: 44777
    }
    bound_http_address {
      host: "127.28.112.190"
      port: 44435
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.28.112.189"
      port: 42609
    }
    bound_http_address {
      host: "127.28.112.189"
      port: 44157
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.28.112.188"
      port: 33963
    }
    bound_http_address {
      host: "127.28.112.188"
      port: 34617
    }
  }
}

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

14:26:20.967 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.28.112.129"
      port: 40055
    }
    bound_http_address {
      host: "127.28.112.129"
      port: 40391
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.28.112.130"
      port: 42385
    }
    bound_http_address {
      host: "127.28.112.130"
      port: 41957
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.28.112.131"
      port: 42521
    }
    bound_http_address {
      host: "127.28.112.131"
      port: 40897
    }
  }
}

14:26:20.968 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
14:26:21.147 [DEBUG - main] (CompositeStrategy.java:59) Unable to load modern Subject methods, relying only on legacy methods
java.lang.NoSuchMethodException: javax.security.auth.Subject.current()
	at java.base/java.lang.Class.getDeclaredMethod(Class.java:2677)
	at org.apache.kudu.client.internals.ModernStrategy.<init>(ModernStrategy.java:51)
	at org.apache.kudu.client.internals.CompositeStrategy.<init>(CompositeStrategy.java:52)
	at org.apache.kudu.client.internals.CompositeStrategy.<clinit>(CompositeStrategy.java:41)
	at org.apache.kudu.client.internals.SecurityManagerCompatibility.get(SecurityManagerCompatibility.java:43)
	at org.apache.kudu.client.SecurityContext.setupSubject(SecurityContext.java:155)
	at org.apache.kudu.client.SecurityContext.<init>(SecurityContext.java:138)
	at org.apache.kudu.client.AsyncKuduClient.<init>(AsyncKuduClient.java:431)
	at org.apache.kudu.client.AsyncKuduClient$AsyncKuduClientBuilder.build(AsyncKuduClient.java:3109)
	at org.apache.kudu.test.KuduTestHarness.before(KuduTestHarness.java:161)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:50)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.doOneAttempt(RetryRule.java:225)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:246)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.runner.JUnitCore.runMain(JUnitCore.java:77)
	at org.junit.runner.JUnitCore.main(JUnitCore.java:36)
14:26:21.204 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
14:26:21.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.307629 29366 catalog_manager.cc:1295] Loaded cluster ID: efb07dfebf044f58b9ffbf8b55363834
14:26:21.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.307711 29366 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: loading cluster ID for follower catalog manager: success
14:26:21.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.309171 29366 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: acquiring CA information for follower catalog manager: success
14:26:21.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.309552 29366 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 473b9d0cb1814d23a24210ef6c390ee7: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:26:21.351 [DEBUG - main] (Connection.java:748) [peer master-127.28.112.188:33963(127.28.112.188:33963)] connecting to peer
14:26:21.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.354387 29394 catalog_manager.cc:1295] Loaded cluster ID: efb07dfebf044f58b9ffbf8b55363834
14:26:21.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.354645 29394 catalog_manager.cc:1588] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: loading cluster ID for follower catalog manager: success
14:26:21.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.356096 29394 catalog_manager.cc:1610] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: acquiring CA information for follower catalog manager: success
14:26:21.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.356545 29394 catalog_manager.cc:1638] T 00000000000000000000000000000000 P bc15a8f7a2b44eb0b5bdc753c826dd6e: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:26:21.420 [DEBUG - main] (Connection.java:748) [peer master-127.28.112.190:44777(127.28.112.190:44777)] connecting to peer
14:26:21.422 [DEBUG - main] (Connection.java:748) [peer master-127.28.112.189:42609(127.28.112.189:42609)] connecting to peer
14:26:21.438 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.28.112.189:42609(127.28.112.189:42609)] Successfully connected to peer
14:26:21.438 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.28.112.190:44777(127.28.112.190:44777)] Successfully connected to peer
14:26:21.438 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.28.112.188:33963(127.28.112.188:33963)] Successfully connected to peer
14:26:21.539 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.28.112.188
14:26:21.539 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.28.112.189
14:26:21.549 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.28.112.190
14:26:21.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.692688 29536 heartbeater.cc:499] Master 127.28.112.188:33963 was elected leader, sending a full tablet report...
14:26:21.780 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x1c05dfc3, L:/127.0.0.1:56944 - R:/127.28.112.188:33963] using SASL/PLAIN
14:26:21.780 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xbe44c0ee, L:/127.0.0.1:60176 - R:/127.28.112.190:44777] using SASL/PLAIN
14:26:21.781 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0x6ee4be06, L:/127.0.0.1:47960 - R:/127.28.112.189:42609] using SASL/PLAIN
14:26:21.818 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
14:26:21.819 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=505), Traces: [0ms] refreshing cache from master, [24ms] Sub RPC ConnectToMaster: sending RPC to server master-127.28.112.188:33963, [103ms] Sub RPC ConnectToMaster: sending RPC to server master-127.28.112.190:44777, [105ms] Sub RPC ConnectToMaster: sending RPC to server master-127.28.112.189:42609, [477ms] Sub RPC ConnectToMaster: received response from server master-127.28.112.189:42609: OK, [477ms] Sub RPC ConnectToMaster: received response from server master-127.28.112.190:44777: OK, [478ms] Sub RPC ConnectToMaster: received response from server master-127.28.112.188:33963: OK, deferred=null) after lookup
14:26:21.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.823473 29671 heartbeater.cc:499] Master 127.28.112.188:33963 was elected leader, sending a full tablet report...
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.880064 29308 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:56944:
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1779114379328"
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:26:21.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
14:26:21.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
14:26:21.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
14:26:21.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
14:26:21.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
14:26:21.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:26:21.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:26:21.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:21.880402 29308 catalog_manager.cc:7059] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table org.apache.kudu.client.TestHybridTime-1779114379328 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
14:26:21.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.895897 29605 tablet_service.cc:1511] Processing CreateTablet for tablet a7e2f415a4c547b9a8c56c187fd5ef16 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114379328 [id=ad3d084f9e614091884c5ddf752dde44]), partition=RANGE (key) PARTITION UNBOUNDED
14:26:21.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.896095 29740 tablet_service.cc:1511] Processing CreateTablet for tablet a7e2f415a4c547b9a8c56c187fd5ef16 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114379328 [id=ad3d084f9e614091884c5ddf752dde44]), partition=RANGE (key) PARTITION UNBOUNDED
14:26:21.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.896240 29605 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a7e2f415a4c547b9a8c56c187fd5ef16. 1 dirs total, 0 dirs full, 0 dirs failed
14:26:21.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.896399 29740 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a7e2f415a4c547b9a8c56c187fd5ef16. 1 dirs total, 0 dirs full, 0 dirs failed
14:26:21.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.900044 29837 tablet_bootstrap.cc:492] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2: Bootstrap starting.
14:26:21.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.900699 29837 tablet_bootstrap.cc:654] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2: Neither blocks nor log segments found. Creating new log.
14:26:21.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.900982 29837 log.cc:826] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2: Log is configured to *not* fsync() on all Append() calls
14:26:21.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.901335 29836 tablet_bootstrap.cc:492] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0: Bootstrap starting.
14:26:21.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.901904 29836 tablet_bootstrap.cc:654] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0: Neither blocks nor log segments found. Creating new log.
14:26:21.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.902163 29836 log.cc:826] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0: Log is configured to *not* fsync() on all Append() calls
14:26:21.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.902868 29837 tablet_bootstrap.cc:492] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2: No bootstrap required, opened a new log
14:26:21.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.902891 29836 tablet_bootstrap.cc:492] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0: No bootstrap required, opened a new log
14:26:21.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.902932 29837 ts_tablet_manager.cc:1403] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
14:26:21.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.902942 29836 ts_tablet_manager.cc:1403] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
14:26:21.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.903074 29468 tablet_service.cc:1511] Processing CreateTablet for tablet a7e2f415a4c547b9a8c56c187fd5ef16 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114379328 [id=ad3d084f9e614091884c5ddf752dde44]), partition=RANGE (key) PARTITION UNBOUNDED
14:26:21.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.903354 29468 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a7e2f415a4c547b9a8c56c187fd5ef16. 1 dirs total, 0 dirs full, 0 dirs failed
14:26:21.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.904523 29836 raft_consensus.cc:359] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.904688 29836 raft_consensus.cc:385] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:26:21.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.904719 29836 raft_consensus.cc:740] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3206628999d14b4ba8b0e3be3678d0e0, State: Initialized, Role: FOLLOWER
14:26:21.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.904814 29836 consensus_queue.cc:260] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [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: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.905032 29836 ts_tablet_manager.cc:1434] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0: Time spent starting tablet: real 0.002s	user 0.002s	sys 0.000s
14:26:21.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.905694 29837 raft_consensus.cc:359] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.905831 29837 raft_consensus.cc:385] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:26:21.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.905860 29837 raft_consensus.cc:740] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 65ec72d3d774458c96c61cd10b6fb9b2, State: Initialized, Role: FOLLOWER
14:26:21.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.905951 29837 consensus_queue.cc:260] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [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: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.906572 29837 ts_tablet_manager.cc:1434] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2: Time spent starting tablet: real 0.004s	user 0.002s	sys 0.000s
14:26:21.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.907430 29806 heartbeater.cc:499] Master 127.28.112.188:33963 was elected leader, sending a full tablet report...
14:26:21.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.908404 29842 raft_consensus.cc:493] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:26:21.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.908501 29842 raft_consensus.cc:515] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.908864 29842 leader_election.cc:290] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 3206628999d14b4ba8b0e3be3678d0e0 (127.28.112.130:42385), a03ece0c28664ad38b1e2c72e7090eeb (127.28.112.129:40055)
14:26:21.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.910461 29841 tablet_bootstrap.cc:492] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb: Bootstrap starting.
14:26:21.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.911229 29841 tablet_bootstrap.cc:654] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb: Neither blocks nor log segments found. Creating new log.
14:26:21.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.911557 29841 log.cc:826] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb: Log is configured to *not* fsync() on all Append() calls
14:26:21.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.915067 29841 tablet_bootstrap.cc:492] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb: No bootstrap required, opened a new log
14:26:21.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.915169 29841 ts_tablet_manager.cc:1403] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb: Time spent bootstrapping tablet: real 0.005s	user 0.001s	sys 0.000s
14:26:21.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.916659 29490 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a7e2f415a4c547b9a8c56c187fd5ef16" candidate_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" is_pre_election: true
14:26:21.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:21.917050 29693 leader_election.cc:343] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [CANDIDATE]: Term 1 pre-election: Tablet error from VoteRequest() call to peer a03ece0c28664ad38b1e2c72e7090eeb (127.28.112.129:40055): Illegal state: must be running to vote when last-logged opid is not known
14:26:21.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.917032 29841 raft_consensus.cc:359] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.917199 29841 raft_consensus.cc:385] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:26:21.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.917235 29841 raft_consensus.cc:740] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a03ece0c28664ad38b1e2c72e7090eeb, State: Initialized, Role: FOLLOWER
14:26:21.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.917349 29841 consensus_queue.cc:260] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb [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: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.917577 29841 ts_tablet_manager.cc:1434] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
14:26:21.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.921485 29625 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a7e2f415a4c547b9a8c56c187fd5ef16" candidate_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3206628999d14b4ba8b0e3be3678d0e0" is_pre_election: true
14:26:21.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.921726 29625 raft_consensus.cc:2468] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 65ec72d3d774458c96c61cd10b6fb9b2 in term 0.
14:26:21.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.921955 29693 leader_election.cc:304] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [CANDIDATE]: Term 1 pre-election: Election decided. Result: candidate won. Election summary: received 3 responses out of 3 voters: 2 yes votes; 1 no votes. yes voters: 3206628999d14b4ba8b0e3be3678d0e0, 65ec72d3d774458c96c61cd10b6fb9b2; no voters: a03ece0c28664ad38b1e2c72e7090eeb
14:26:21.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.922148 29842 raft_consensus.cc:2804] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Leader pre-election won for term 1
14:26:21.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.922228 29842 raft_consensus.cc:493] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:26:21.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.922271 29842 raft_consensus.cc:3060] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 0 FOLLOWER]: Advancing to term 1
14:26:21.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.923162 29842 raft_consensus.cc:515] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.923344 29842 leader_election.cc:290] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [CANDIDATE]: Term 1 election: Requested vote from peers 3206628999d14b4ba8b0e3be3678d0e0 (127.28.112.130:42385), a03ece0c28664ad38b1e2c72e7090eeb (127.28.112.129:40055)
14:26:21.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.923663 29490 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a7e2f415a4c547b9a8c56c187fd5ef16" candidate_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a03ece0c28664ad38b1e2c72e7090eeb"
14:26:21.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.923791 29490 raft_consensus.cc:3060] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb [term 0 FOLLOWER]: Advancing to term 1
14:26:21.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.923861 29625 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a7e2f415a4c547b9a8c56c187fd5ef16" candidate_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3206628999d14b4ba8b0e3be3678d0e0"
14:26:21.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.923940 29625 raft_consensus.cc:3060] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [term 0 FOLLOWER]: Advancing to term 1
14:26:21.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.925058 29625 raft_consensus.cc:2468] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 65ec72d3d774458c96c61cd10b6fb9b2 in term 1.
14:26:21.925 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table ad3d084f9e614091884c5ddf752dde44
14:26:21.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.925341 29490 raft_consensus.cc:2468] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 65ec72d3d774458c96c61cd10b6fb9b2 in term 1.
14:26:21.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.925415 29693 leader_election.cc:304] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [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: 3206628999d14b4ba8b0e3be3678d0e0, 65ec72d3d774458c96c61cd10b6fb9b2; no voters: 
14:26:21.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.925513 29842 raft_consensus.cc:2804] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 1 FOLLOWER]: Leader election won for term 1
14:26:21.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.925665 29842 raft_consensus.cc:697] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [term 1 LEADER]: Becoming Leader. State: Replica: 65ec72d3d774458c96c61cd10b6fb9b2, State: Running, Role: LEADER
14:26:21.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.925760 29842 consensus_queue.cc:237] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [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: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } }
14:26:21.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:21.926730 29308 catalog_manager.cc:5697] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 reported cstate change: term changed from 0 to 1, leader changed from <none> to 65ec72d3d774458c96c61cd10b6fb9b2 (127.28.112.131). New cstate: current_term: 1 leader_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "65ec72d3d774458c96c61cd10b6fb9b2" member_type: VOTER last_known_addr { host: "127.28.112.131" port: 42521 } health_report { overall_health: HEALTHY } } }
14:26:21.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:21.936105 29539 tablet.cc:2404] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:26:21.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:21.936167 29809 tablet.cc:2404] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:26:21.979 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet a7e2f415a4c547b9a8c56c187fd5ef16 for table 'org.apache.kudu.client.TestHybridTime-1779114379328' with partition [<start>, <end>)
14:26:21.987 [DEBUG - kudu-nio-0] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299993, tablet=a7e2f415a4c547b9a8c56c187fd5ef16@[3206628999d14b4ba8b0e3be3678d0e0(127.28.112.130:42385),65ec72d3d774458c96c61cd10b6fb9b2(127.28.112.131:42521)[L],a03ece0c28664ad38b1e2c72e7090eeb(127.28.112.129:40055)]}]
14:26:21.988 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=19), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.28.112.188:33963, [9ms] Sub RPC GetTableLocations: received response from server master-127.28.112.188:33963: OK, deferred=null) after lookup
14:26:21.998 [DEBUG - kudu-nio-0] (Connection.java:748) [peer 65ec72d3d774458c96c61cd10b6fb9b2(127.28.112.131:42521)] connecting to peer
14:26:22.001 [DEBUG - kudu-nio-3] (Connection.java:757) [peer 65ec72d3d774458c96c61cd10b6fb9b2(127.28.112.131:42521)] Successfully connected to peer
14:26:22.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:26:22.065305 29674 tablet.cc:2404] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:26:22.083 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x055fbb57, L:/127.0.0.1:40834 - R:/127.28.112.131:42521] using TOKEN/null
14:26:22.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.086724 29490 raft_consensus.cc:1275] T a7e2f415a4c547b9a8c56c187fd5ef16 P a03ece0c28664ad38b1e2c72e7090eeb [term 1 FOLLOWER]: Refusing update from remote peer 65ec72d3d774458c96c61cd10b6fb9b2: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:26:22.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.087412 29625 raft_consensus.cc:1275] T a7e2f415a4c547b9a8c56c187fd5ef16 P 3206628999d14b4ba8b0e3be3678d0e0 [term 1 FOLLOWER]: Refusing update from remote peer 65ec72d3d774458c96c61cd10b6fb9b2: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:26:22.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.087801 29847 consensus_queue.cc:1048] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [LEADER]: Connected to new peer: Peer: permanent_uuid: "a03ece0c28664ad38b1e2c72e7090eeb" member_type: VOTER last_known_addr { host: "127.28.112.129" port: 40055 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:26:22.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.087916 29842 consensus_queue.cc:1048] T a7e2f415a4c547b9a8c56c187fd5ef16 P 65ec72d3d774458c96c61cd10b6fb9b2 [LEADER]: Connected to new peer: Peer: permanent_uuid: "3206628999d14b4ba8b0e3be3678d0e0" member_type: VOTER last_known_addr { host: "127.28.112.130" port: 42385 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:26:22.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.098389 29851 mvcc.cc:204] Tried to move back new op lower bound from 7287252509024477184 to 7287252508368670720. Current Snapshot: MvccSnapshot[applied={T|T < 7287252509024477184 or (T in {7287252509024477184})}]
14:26:22.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.098901 29852 mvcc.cc:204] Tried to move back new op lower bound from 7287252509024477184 to 7287252508368670720. Current Snapshot: MvccSnapshot[applied={T|T < 7287252509024477184 or (T in {7287252509024477184})}]
14:26:22.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.099273 29854 mvcc.cc:204] Tried to move back new op lower bound from 7287252509024477184 to 7287252508368670720. Current Snapshot: MvccSnapshot[applied={T|T < 7287252509024477184}]
14:26:22.129 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:26:22.138 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:26:22.143 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:26:22.148 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1640309171(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:26:22.157 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:26:22.157 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1470168839(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:26:22.164 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:26:22.164 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1790879511(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:26:22.169 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:26:22.216 [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-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.226 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7287252529504477185) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.230 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7287252529504477189) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.234 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7287252529504477193) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.241 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7287252529504477197) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.246 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7287252529504477201) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.252 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7287252529504477205) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.256 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7287252529504477206) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114379328, tablet=a7e2f415a4c547b9a8c56c187fd5ef16, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:26:22.260 [DEBUG - main] (Connection.java:575) [peer 65ec72d3d774458c96c61cd10b6fb9b2(127.28.112.131:42521)] disconnecting while in state READY
14:26:22.261 [DEBUG - main] (Connection.java:575) [peer master-127.28.112.189:42609(127.28.112.189:42609)] disconnecting while in state READY
14:26:22.261 [DEBUG - main] (Connection.java:575) [peer master-127.28.112.190:44777(127.28.112.190:44777)] disconnecting while in state READY
14:26:22.264 [DEBUG - main] (Connection.java:575) [peer master-127.28.112.188:33963(127.28.112.188:33963)] disconnecting while in state READY
14:26:22.268 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.28.112.188:33963(127.28.112.188:33963)] handling channelInactive
14:26:22.268 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2704) Releasing all remaining resources
14:26:22.268 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.28.112.188:33963(127.28.112.188:33963)] cleaning up while in state READY due to: connection closed
14:26:22.268 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.28.112.190:44777(127.28.112.190:44777)] handling channelInactive
14:26:22.269 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.28.112.190:44777(127.28.112.190:44777)] cleaning up while in state READY due to: connection closed
14:26:22.272 [DEBUG - kudu-nio-3] (Connection.java:239) [peer 65ec72d3d774458c96c61cd10b6fb9b2(127.28.112.131:42521)] handling channelInactive
14:26:22.273 [DEBUG - kudu-nio-3] (Connection.java:709) [peer 65ec72d3d774458c96c61cd10b6fb9b2(127.28.112.131:42521)] cleaning up while in state READY due to: connection closed
14:26:22.274 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.28.112.189:42609(127.28.112.189:42609)] handling channelInactive
14:26:22.275 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.28.112.189:42609(127.28.112.189:42609)] cleaning up while in state READY due to: connection closed
14:26:22.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.275774 29122 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskBFcbTT/build/release/bin/kudu with pid 29408
14:26:22.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.281767 29122 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskBFcbTT/build/release/bin/kudu with pid 29543
14:26:22.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.287979 29122 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskBFcbTT/build/release/bin/kudu with pid 29678
14:26:22.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.294373 29122 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskBFcbTT/build/release/bin/kudu with pid 29133
14:26:22.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.301759 29122 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskBFcbTT/build/release/bin/kudu with pid 29202
14:26:22.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:26:22.307763 29122 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskBFcbTT/build/release/bin/kudu with pid 29275
14:26:22.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:26:22Z chronyd exiting

Time: 2.911

OK (1 test)

