JUnit version 4.13.2
.08:00:36.418 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1783497636408
08:00:36.446 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
08:00:36.506 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
08:00:36.516 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskqFsYDK/test-tmp
08:00:36.524 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/debug/bin
08:00:36.528 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskqFsYDK/java/build/dist-test/../../../build/debug/bin/kudu, test, mini_cluster, --serialization=pb]
08:00:36.747 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

08:00:36.765 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
08:00:36.766 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

08:00:36.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.768239 32232 mini_oidc.cc:150] Starting JWKS server
08:00:36.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.770323 32232 webserver.cc:533] Webserver started at http://127.0.0.1:40383/ using document root <none> and password file <none>
08:00:36.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.770522 32232 mini_oidc.cc:186] Starting OIDC Discovery server
08:00:36.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.770787 32232 webserver.cc:533] Webserver started at http://127.0.0.1:42535/ using document root <none> and password file <none>
08:00:36.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T08:00:36Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
08:00:36.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T08:00:36Z Disabled control of system clock
08:00:36.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.787825 32232 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:36.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:36.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/wal
08:00:36.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data
08:00:36.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:00:36.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:00:36.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:00:36.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:36.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:36.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:36.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/logs
08:00:36.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data/info.pb
08:00:36.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:36.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:00:36.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:00:36.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:00:36.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:00:36.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:36.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:36.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:36.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
08:00:36.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:00:36.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:00:36.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:00:36.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.62:46745
08:00:36.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.62
08:00:36.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:36.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:36.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:36.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:36.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:36.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:00:36.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:36.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:00:36.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40383/jwks.json with env {}
08:00:36.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.903537 32243 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:00:36.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.903746 32243 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:00:36.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.903788 32243 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:00:36.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.907487 32243 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
08:00:36.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.907574 32243 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:00:36.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.907594 32243 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
08:00:36.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.907610 32243 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
08:00:36.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.907627 32243 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
08:00:36.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.907640 32243 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:40383/jwks.json
08:00:36.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.911813 32243 master_runner.cc:387] Master server non-default flags:
08:00:36.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:36.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:36.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:36.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:36.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data
08:00:36.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/wal
08:00:36.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:00:36.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:36.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:36.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:36.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:00:36.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.62:46745
08:00:36.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:00:36.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:00:36.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:00:36.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40383/jwks.json
08:00:36.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data/info.pb
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.62
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.32243
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:36.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/logs
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
08:00:36.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:00:36.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
08:00:36.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
08:00:36.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 07:43:13 UTC on bdcb31816ec0
08:00:36.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12768
08:00:36.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.913079 32243 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:00:36.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.914424 32243 file_cache.cc:504] Constructed file cache file cache with capacity 419430
08:00:36.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.921072 32251 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
08:00:36.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.921077 32249 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
08:00:36.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.921293 32243 server_base.cc:1061] running on GCE node
08:00:36.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.921084 32248 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
08:00:36.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.922518 32243 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:00:36.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.923427 32243 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:00:36.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.924575 32243 hybrid_clock.cc:648] HybridClock initialized: now 1783497636924557 us; error 36 us; skew 500 ppm
08:00:36.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.926275 32243 webserver.cc:533] Webserver started at http://127.31.122.62:38191/ using document root <none> and password file <none>
08:00:36.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.926838 32243 fs_manager.cc:362] Metadata directory not provided
08:00:36.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.926894 32243 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:00:36.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.927057 32243 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:00:36.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.928606 32243 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data/instance:
08:00:36.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d556d31bf22e49d8bfc2debaa78f9aa7"
08:00:36.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:36 on dist-test-slave-kkww"
08:00:36.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.929071 32243 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/wal/instance:
08:00:36.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d556d31bf22e49d8bfc2debaa78f9aa7"
08:00:36.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:36 on dist-test-slave-kkww"
08:00:36.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.932679 32243 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
08:00:36.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.934655 32257 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:00:36.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.935599 32243 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
08:00:36.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.935691 32243 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data,/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/wal
08:00:36.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d556d31bf22e49d8bfc2debaa78f9aa7"
08:00:36.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:36 on dist-test-slave-kkww"
08:00:36.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.935756 32243 fs_report.cc:389] FS layout report
08:00:36.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:00:36.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/wal
08:00:36.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/wal
08:00:36.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data/data
08:00:36.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:00:36.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:00:36.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:00:36.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:00:36.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:00:36.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:00:36.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:00:36.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:00:36.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:00:36.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:00:36.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:00:36.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.951370 32243 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:00:36.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.952037 32243 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:00:36.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.952180 32243 kserver.cc:163] Server-wide thread pool size limit: 3276
08:00:36.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.958703 32309 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.122.62:46745 every 8 connection(s)
08:00:36.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.958701 32243 rpc_server.cc:307] RPC server started. Bound to: 127.31.122.62:46745
08:00:36.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.959731 32243 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/data/info.pb
08:00:36.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.962425 32310 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
08:00:36.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.964345 32232 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu as pid 32243
08:00:36.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.964496 32232 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-0/wal/instance
08:00:36.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.966182 32232 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:36.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:36.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/wal
08:00:36.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data
08:00:36.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:00:36.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:00:36.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:00:36.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:36.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:36.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:36.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/logs
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data/info.pb
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:36.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:36.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:36.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
08:00:36.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:00:36.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:00:36.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:00:36.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.61:44761
08:00:36.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.61
08:00:36.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:36.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:36.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:36.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:36.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:36.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:00:36.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:36.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:00:36.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40383/jwks.json with env {}
08:00:36.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.965888 32310 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } has no permanent_uuid. Determining permanent_uuid...
08:00:36.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:36.978088 32310 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } has no permanent_uuid. Determining permanent_uuid...
08:00:36.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.978919 32260 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.31.122.61:44761: connect: Connection refused (error 111)
08:00:36.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:36.979877 32310 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.61:44761: Network error: Client connection negotiation failed: client connection to 127.31.122.61:44761: connect: Connection refused (error 111)
08:00:37.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.029102 32310 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } attempt: 1
08:00:37.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.030779 32310 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.61:44761: Network error: Client connection negotiation failed: client connection to 127.31.122.61:44761: connect: Connection refused (error 111)
08:00:37.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.068013 32312 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:00:37.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.068282 32312 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:00:37.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.068338 32312 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:00:37.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.071695 32312 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
08:00:37.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.071751 32312 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:00:37.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.071771 32312 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
08:00:37.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.071787 32312 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
08:00:37.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.071805 32312 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
08:00:37.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.071817 32312 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:40383/jwks.json
08:00:37.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.075707 32312 master_runner.cc:387] Master server non-default flags:
08:00:37.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:37.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:37.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:37.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:37.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data
08:00:37.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/wal
08:00:37.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:00:37.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:37.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:37.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:37.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:00:37.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.61:44761
08:00:37.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40383/jwks.json
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data/info.pb
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.61
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:37.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:00:37.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.32312
08:00:37.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:37.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:00:37.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:00:37.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:37.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/logs
08:00:37.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:37.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:00:37.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:00:37.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
08:00:37.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:00:37.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
08:00:37.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
08:00:37.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 07:43:13 UTC on bdcb31816ec0
08:00:37.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12768
08:00:37.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.076713 32312 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:00:37.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.077797 32312 file_cache.cc:504] Constructed file cache file cache with capacity 419430
08:00:37.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.083917 32320 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
08:00:37.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.083952 32321 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
08:00:37.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.083921 32323 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
08:00:37.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.084559 32312 server_base.cc:1061] running on GCE node
08:00:37.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.085264 32312 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:00:37.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.086336 32312 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:00:37.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.087493 32312 hybrid_clock.cc:648] HybridClock initialized: now 1783497637087481 us; error 29 us; skew 500 ppm
08:00:37.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.089205 32312 webserver.cc:533] Webserver started at http://127.31.122.61:42843/ using document root <none> and password file <none>
08:00:37.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.089830 32312 fs_manager.cc:362] Metadata directory not provided
08:00:37.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.089891 32312 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:00:37.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.090108 32312 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:00:37.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.091751 32312 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data/instance:
08:00:37.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "16d20124b52e40c4a392d94dbacda5d9"
08:00:37.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.092258 32312 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/wal/instance:
08:00:37.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "16d20124b52e40c4a392d94dbacda5d9"
08:00:37.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.095727 32312 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
08:00:37.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.097831 32329 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:00:37.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.098927 32312 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
08:00:37.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.098949 32310 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } attempt: 2
08:00:37.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.099035 32312 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data,/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/wal
08:00:37.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "16d20124b52e40c4a392d94dbacda5d9"
08:00:37.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.099117 32312 fs_report.cc:389] FS layout report
08:00:37.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:00:37.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/wal
08:00:37.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/wal
08:00:37.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data/data
08:00:37.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:00:37.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:00:37.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:00:37.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:00:37.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:00:37.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:00:37.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:00:37.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:00:37.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:00:37.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:00:37.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:00:37.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.100628 32310 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.61:44761: Network error: Client connection negotiation failed: client connection to 127.31.122.61:44761: connect: Connection refused (error 111)
08:00:37.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.130488 32312 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:00:37.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.131167 32312 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:00:37.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.131311 32312 kserver.cc:163] Server-wide thread pool size limit: 3276
08:00:37.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.138018 32381 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.122.61:44761 every 8 connection(s)
08:00:37.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.138026 32312 rpc_server.cc:307] RPC server started. Bound to: 127.31.122.61:44761
08:00:37.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.139148 32312 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/data/info.pb
08:00:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.142195 32382 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
08:00:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.143152 32232 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu as pid 32312
08:00:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.143268 32232 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-1/wal/instance
08:00:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.143955 32232 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/wal
08:00:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data
08:00:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:00:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/logs
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data/info.pb
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
08:00:37.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:00:37.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:00:37.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:00:37.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.60:38193
08:00:37.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.60
08:00:37.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:37.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:37.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:00:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:00:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40383/jwks.json with env {}
08:00:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.145666 32382 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } has no permanent_uuid. Determining permanent_uuid...
08:00:37.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.156443 32382 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } has no permanent_uuid. Determining permanent_uuid...
08:00:37.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.162675 32382 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } has no permanent_uuid. Determining permanent_uuid...
08:00:37.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.163475 32331 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.31.122.60:38193: connect: Connection refused (error 111)
08:00:37.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.164386 32382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.60:38193: Network error: Client connection negotiation failed: client connection to 127.31.122.60:38193: connect: Connection refused (error 111)
08:00:37.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.191781 32310 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } attempt: 3
08:00:37.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.196158 32310 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } has no permanent_uuid. Determining permanent_uuid...
08:00:37.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.197458 32310 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.60:38193: Network error: Client connection negotiation failed: client connection to 127.31.122.60:38193: connect: Connection refused (error 111)
08:00:37.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.213606 32382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } attempt: 1
08:00:37.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.215080 32382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.60:38193: Network error: Client connection negotiation failed: client connection to 127.31.122.60:38193: connect: Connection refused (error 111)
08:00:37.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.228613 32310 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } attempt: 1
08:00:37.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.230031 32310 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.60:38193: Network error: Client connection negotiation failed: client connection to 127.31.122.60:38193: connect: Connection refused (error 111)
08:00:37.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.252272 32384 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:00:37.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.252480 32384 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:00:37.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.252522 32384 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:00:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.255795 32384 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
08:00:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.255846 32384 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:00:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.255865 32384 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
08:00:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.255880 32384 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
08:00:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.255897 32384 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
08:00:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.255918 32384 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:40383/jwks.json
08:00:37.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.259759 32384 master_runner.cc:387] Master server non-default flags:
08:00:37.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:37.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:37.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:37.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:37.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data
08:00:37.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/wal
08:00:37.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:00:37.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:37.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:37.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:37.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:00:37.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.60:38193
08:00:37.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:00:37.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:00:37.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:00:37.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40383/jwks.json
08:00:37.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data/info.pb
08:00:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.60
08:00:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:00:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.32384
08:00:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:00:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:00:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/logs
08:00:37.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:37.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:00:37.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:00:37.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
08:00:37.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:00:37.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
08:00:37.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
08:00:37.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 07:43:13 UTC on bdcb31816ec0
08:00:37.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12768
08:00:37.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.260845 32384 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:00:37.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.261924 32384 file_cache.cc:504] Constructed file cache file cache with capacity 419430
08:00:37.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.268523 32395 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
08:00:37.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.269258 32393 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
08:00:37.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.269265 32392 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
08:00:37.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.269934 32384 server_base.cc:1061] running on GCE node
08:00:37.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.270490 32384 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:00:37.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.271660 32384 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:00:37.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.272842 32384 hybrid_clock.cc:648] HybridClock initialized: now 1783497637272782 us; error 76 us; skew 500 ppm
08:00:37.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.275081 32384 webserver.cc:533] Webserver started at http://127.31.122.60:35675/ using document root <none> and password file <none>
08:00:37.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.275801 32384 fs_manager.cc:362] Metadata directory not provided
08:00:37.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.275883 32384 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:00:37.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.276119 32384 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:00:37.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.277989 32384 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data/instance:
08:00:37.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "874a1ad8747e4195a76a8c9d7c9e696e"
08:00:37.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.278486 32384 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/wal/instance:
08:00:37.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "874a1ad8747e4195a76a8c9d7c9e696e"
08:00:37.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.282011 32384 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.002s	sys 0.004s
08:00:37.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.283246 32382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } attempt: 2
08:00:37.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.284255 32401 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.284848 32382 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.60:38193: Network error: Client connection negotiation failed: client connection to 127.31.122.60:38193: connect: Connection refused (error 111)
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.285344 32384 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.285444 32384 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data,/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/wal
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "874a1ad8747e4195a76a8c9d7c9e696e"
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.285517 32384 fs_report.cc:389] FS layout report
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/wal
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/wal
08:00:37.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data/data
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:00:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:00:37.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.305188 32310 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } attempt: 2
08:00:37.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.306730 32310 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.122.60:38193: Network error: Client connection negotiation failed: client connection to 127.31.122.60:38193: connect: Connection refused (error 111)
08:00:37.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.307466 32384 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:00:37.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.308174 32384 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:00:37.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.308339 32384 kserver.cc:163] Server-wide thread pool size limit: 3276
08:00:37.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.315605 32453 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.122.60:38193 every 8 connection(s)
08:00:37.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.315629 32384 rpc_server.cc:307] RPC server started. Bound to: 127.31.122.60:38193
08:00:37.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.316797 32384 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/data/info.pb
08:00:37.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.319631 32454 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
08:00:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.321172 32232 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu as pid 32384
08:00:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.321326 32232 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/master-2/wal/instance
08:00:37.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.323351 32454 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } has no permanent_uuid. Determining permanent_uuid...
08:00:37.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.331449 32454 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } has no permanent_uuid. Determining permanent_uuid...
08:00:37.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.336083 32454 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } has no permanent_uuid. Determining permanent_uuid...
08:00:37.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.341566 32454 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e: Bootstrap starting.
08:00:37.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.344316 32454 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e: Neither blocks nor log segments found. Creating new log.
08:00:37.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.345088 32454 log.cc:826] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e: Log is configured to *not* fsync() on all Append() calls
08:00:37.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.346745 32454 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e: No bootstrap required, opened a new log
08:00:37.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.349754 32454 raft_consensus.cc:359] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.349922 32454 raft_consensus.cc:385] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:00:37.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.349980 32454 raft_consensus.cc:740] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 874a1ad8747e4195a76a8c9d7c9e696e, State: Initialized, Role: FOLLOWER
08:00:37.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.350495 32454 consensus_queue.cc:260] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [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: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.350991 32463 sys_catalog.cc:455] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [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: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.351122 32463 sys_catalog.cc:458] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: This master's current role is: FOLLOWER
08:00:37.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.351722 32454 sys_catalog.cc:565] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: configured and running, proceeding with master startup.
08:00:37.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.355345 32384 master_runner.cc:187] Error getting master registration for 127.31.122.62:46745: OK, instance_id { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" instance_seqno: 1783497636956993 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
08:00:37.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.356910 32474 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
08:00:37.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.356971 32474 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
08:00:37.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.357008 32384 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
08:00:37.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.376120 32382 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } attempt: 3
08:00:37.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.382539 32382 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: Bootstrap starting.
08:00:37.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.385339 32382 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: Neither blocks nor log segments found. Creating new log.
08:00:37.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.386541 32382 log.cc:826] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: Log is configured to *not* fsync() on all Append() calls
08:00:37.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.388800 32382 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: No bootstrap required, opened a new log
08:00:37.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.392125 32382 raft_consensus.cc:359] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.392321 32382 raft_consensus.cc:385] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:00:37.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.392371 32382 raft_consensus.cc:740] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 16d20124b52e40c4a392d94dbacda5d9, State: Initialized, Role: FOLLOWER
08:00:37.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.392840 32382 consensus_queue.cc:260] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [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: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.393325 32477 sys_catalog.cc:455] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [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: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.393460 32477 sys_catalog.cc:458] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [sys.catalog]: This master's current role is: FOLLOWER
08:00:37.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.394134 32382 sys_catalog.cc:565] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [sys.catalog]: configured and running, proceeding with master startup.
08:00:37.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.399271 32488 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
08:00:37.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.399350 32488 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
08:00:37.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.398125 32312 master_runner.cc:187] Error getting master registration for 127.31.122.62:46745: OK, instance_id { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" instance_seqno: 1783497636956993 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
08:00:37.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.400343 32312 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
08:00:37.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.405900 32310 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } attempt: 3
08:00:37.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.412946 32310 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: Bootstrap starting.
08:00:37.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.415715 32310 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: Neither blocks nor log segments found. Creating new log.
08:00:37.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.416838 32310 log.cc:826] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: Log is configured to *not* fsync() on all Append() calls
08:00:37.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.418787 32310 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: No bootstrap required, opened a new log
08:00:37.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.422176 32310 raft_consensus.cc:359] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.422381 32310 raft_consensus.cc:385] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:00:37.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.422431 32310 raft_consensus.cc:740] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d556d31bf22e49d8bfc2debaa78f9aa7, State: Initialized, Role: FOLLOWER
08:00:37.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.422953 32310 consensus_queue.cc:260] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [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: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.423503 32491 sys_catalog.cc:455] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [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: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.423955 32491 sys_catalog.cc:458] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [sys.catalog]: This master's current role is: FOLLOWER
08:00:37.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.424328 32310 sys_catalog.cc:565] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [sys.catalog]: configured and running, proceeding with master startup.
08:00:37.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.429800 32502 catalog_manager.cc:1594] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
08:00:37.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.440119 32502 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
08:00:37.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.442065 32243 master_runner.cc:264] No leader master found from master d556d31bf22e49d8bfc2debaa78f9aa7
08:00:37.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.442142 32243 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
08:00:37.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.453547 32263 tablet.cc:2406] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:00:37.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.559561 32407 tablet.cc:2406] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:00:37.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.632674 32335 tablet.cc:2406] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:00:37.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.701031 32463 raft_consensus.cc:493] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
08:00:37.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.701210 32463 raft_consensus.cc:515] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.702306 32463 leader_election.cc:290] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers d556d31bf22e49d8bfc2debaa78f9aa7 (127.31.122.62:46745), 16d20124b52e40c4a392d94dbacda5d9 (127.31.122.61:44761)
08:00:37.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.702913 32284 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" is_pre_election: true
08:00:37.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.702905 32356 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "16d20124b52e40c4a392d94dbacda5d9" is_pre_election: true
08:00:37.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.703194 32356 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 874a1ad8747e4195a76a8c9d7c9e696e in term 0.
08:00:37.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.703194 32284 raft_consensus.cc:2468] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 874a1ad8747e4195a76a8c9d7c9e696e in term 0.
08:00:37.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.703641 32403 leader_election.cc:304] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [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: 874a1ad8747e4195a76a8c9d7c9e696e, d556d31bf22e49d8bfc2debaa78f9aa7; no voters: 
08:00:37.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.703989 32463 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Leader pre-election won for term 1
08:00:37.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.704067 32463 raft_consensus.cc:493] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
08:00:37.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.704114 32463 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 0 FOLLOWER]: Advancing to term 1
08:00:37.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.705142 32463 raft_consensus.cc:515] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.705530 32463 leader_election.cc:290] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [CANDIDATE]: Term 1 election: Requested vote from peers d556d31bf22e49d8bfc2debaa78f9aa7 (127.31.122.62:46745), 16d20124b52e40c4a392d94dbacda5d9 (127.31.122.61:44761)
08:00:37.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.705824 32284 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7"
08:00:37.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.705830 32356 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "16d20124b52e40c4a392d94dbacda5d9"
08:00:37.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.705941 32356 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [term 0 FOLLOWER]: Advancing to term 1
08:00:37.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.705940 32284 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [term 0 FOLLOWER]: Advancing to term 1
08:00:37.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.706925 32284 raft_consensus.cc:2468] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 874a1ad8747e4195a76a8c9d7c9e696e in term 1.
08:00:37.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.706919 32356 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 874a1ad8747e4195a76a8c9d7c9e696e in term 1.
08:00:37.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.707254 32404 leader_election.cc:304] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [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: 16d20124b52e40c4a392d94dbacda5d9, 874a1ad8747e4195a76a8c9d7c9e696e; no voters: 
08:00:37.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.707453 32463 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 1 FOLLOWER]: Leader election won for term 1
08:00:37.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.707674 32463 raft_consensus.cc:697] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [term 1 LEADER]: Becoming Leader. State: Replica: 874a1ad8747e4195a76a8c9d7c9e696e, State: Running, Role: LEADER
08:00:37.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.708016 32463 consensus_queue.cc:237] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [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: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } }
08:00:37.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.709971 32504 sys_catalog.cc:455] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 874a1ad8747e4195a76a8c9d7c9e696e. Latest consensus state: current_term: 1 leader_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.710088 32504 sys_catalog.cc:458] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: This master's current role is: LEADER
08:00:37.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.710697 32506 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
08:00:37.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.711638 32506 catalog_manager.cc:1520] Initializing Kudu cluster ID...
08:00:37.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.716550 32356 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [term 1 FOLLOWER]: Refusing update from remote peer 874a1ad8747e4195a76a8c9d7c9e696e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
08:00:37.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.716550 32284 raft_consensus.cc:1275] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [term 1 FOLLOWER]: Refusing update from remote peer 874a1ad8747e4195a76a8c9d7c9e696e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
08:00:37.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.717244 32504 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [LEADER]: Connected to new peer: Peer: permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
08:00:37.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.717726 32463 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [LEADER]: Connected to new peer: Peer: permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
08:00:37.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.721356 32491 sys_catalog.cc:455] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 874a1ad8747e4195a76a8c9d7c9e696e. Latest consensus state: current_term: 1 leader_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.721486 32491 sys_catalog.cc:458] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [sys.catalog]: This master's current role is: FOLLOWER
08:00:37.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.721858 32477 sys_catalog.cc:455] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 874a1ad8747e4195a76a8c9d7c9e696e. Latest consensus state: current_term: 1 leader_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.721962 32477 sys_catalog.cc:458] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [sys.catalog]: This master's current role is: FOLLOWER
08:00:37.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.723917 32463 sys_catalog.cc:455] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.724047 32463 sys_catalog.cc:458] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: This master's current role is: LEADER
08:00:37.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.723996 32513 sys_catalog.cc:455] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.724099 32513 sys_catalog.cc:458] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e [sys.catalog]: This master's current role is: LEADER
08:00:37.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.724686 32477 sys_catalog.cc:455] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.724793 32477 sys_catalog.cc:458] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9 [sys.catalog]: This master's current role is: FOLLOWER
08:00:37.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.725859 32491 sys_catalog.cc:455] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "d556d31bf22e49d8bfc2debaa78f9aa7" member_type: VOTER last_known_addr { host: "127.31.122.62" port: 46745 } } peers { permanent_uuid: "16d20124b52e40c4a392d94dbacda5d9" member_type: VOTER last_known_addr { host: "127.31.122.61" port: 44761 } } peers { permanent_uuid: "874a1ad8747e4195a76a8c9d7c9e696e" member_type: VOTER last_known_addr { host: "127.31.122.60" port: 38193 } } }
08:00:37.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.725968 32491 sys_catalog.cc:458] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7 [sys.catalog]: This master's current role is: FOLLOWER
08:00:37.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.728523 32506 catalog_manager.cc:1383] Generated new cluster ID: 273e46b6ca1444d7b708f126be6f66b2
08:00:37.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.728585 32506 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
08:00:37.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.747725 32506 catalog_manager.cc:1406] Generated new certificate authority record
08:00:37.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.748791 32506 catalog_manager.cc:1540] Loading token signing keys...
08:00:37.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.759042 32506 catalog_manager.cc:6070] T 00000000000000000000000000000000 P 874a1ad8747e4195a76a8c9d7c9e696e: Generated new TSK 0
08:00:37.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.759949 32506 catalog_manager.cc:1550] Initializing in-progress tserver states...
08:00:37.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.764746 32232 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/wal
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/logs
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data/info.pb
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:37.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.1:0
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.122.1
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.1
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:37.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
08:00:37.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.867606 32516 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:00:37.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.867831 32516 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:00:37.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.867873 32516 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:00:37.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.871279 32516 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:00:37.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.871394 32516 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.122.1
08:00:37.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.875430 32516 tablet_server_runner.cc:78] Tablet server non-default flags:
08:00:37.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:37.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:37.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:37.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/wal
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.1:0
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data/info.pb
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.1
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.32516
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:37.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.122.1
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/logs
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
08:00:37.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 07:43:13 UTC on bdcb31816ec0
08:00:37.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12768
08:00:37.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.876538 32516 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:00:37.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.877589 32516 file_cache.cc:504] Constructed file cache file cache with capacity 419430
08:00:37.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.883616 32522 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
08:00:37.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.883616 32521 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
08:00:37.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:37.884083 32524 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
08:00:37.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.884451 32516 server_base.cc:1061] running on GCE node
08:00:37.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.884840 32516 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:00:37.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.885421 32516 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:00:37.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.886576 32516 hybrid_clock.cc:648] HybridClock initialized: now 1783497637886562 us; error 26 us; skew 500 ppm
08:00:37.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.888329 32516 webserver.cc:533] Webserver started at http://127.31.122.1:42007/ using document root <none> and password file <none>
08:00:37.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.888833 32516 fs_manager.cc:362] Metadata directory not provided
08:00:37.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.888880 32516 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:00:37.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.889037 32516 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:00:37.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.890465 32516 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data/instance:
08:00:37.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f274fc0a97894d6ca422e2622ba3e2f9"
08:00:37.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.890869 32516 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/wal/instance:
08:00:37.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f274fc0a97894d6ca422e2622ba3e2f9"
08:00:37.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.893955 32516 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.001s	sys 0.004s
08:00:37.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.896324 32530 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:00:37.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.897375 32516 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
08:00:37.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.897471 32516 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data,/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/wal
08:00:37.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f274fc0a97894d6ca422e2622ba3e2f9"
08:00:37.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:37 on dist-test-slave-kkww"
08:00:37.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.897554 32516 fs_report.cc:389] FS layout report
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/wal
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/wal
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data/data
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:00:37.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:00:37.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:00:37.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.915712 32516 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:00:37.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.916363 32516 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:00:37.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.916507 32516 kserver.cc:163] Server-wide thread pool size limit: 3276
08:00:37.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.917116 32516 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
08:00:37.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.918111 32516 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:00:37.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.918157 32516 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
08:00:37.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.918193 32516 ts_tablet_manager.cc:616] Registered 0 tablets
08:00:37.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.918207 32516 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:00:37.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.926789 32516 rpc_server.cc:307] RPC server started. Bound to: 127.31.122.1:46813
08:00:37.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.926844 32643 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.122.1:46813 every 8 connection(s)
08:00:37.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.927874 32516 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/data/info.pb
08:00:37.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.930495 32232 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu as pid 32516
08:00:37.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.930598 32232 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-0/wal/instance
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.932927 32232 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/wal
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:37.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/logs
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data/info.pb
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:37.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.2:0
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.122.2
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.2
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:37.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
08:00:37.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.938524 32644 heartbeater.cc:344] Connected to a master server at 127.31.122.60:38193
08:00:37.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.938704 32645 heartbeater.cc:344] Connected to a master server at 127.31.122.62:46745
08:00:37.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.938807 32644 heartbeater.cc:461] Registering TS with master...
08:00:37.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.938807 32645 heartbeater.cc:461] Registering TS with master...
08:00:37.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.939502 32645 heartbeater.cc:507] Master 127.31.122.62:46745 requested a full tablet report, sending...
08:00:37.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.939560 32646 heartbeater.cc:344] Connected to a master server at 127.31.122.61:44761
08:00:37.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.939641 32646 heartbeater.cc:461] Registering TS with master...
08:00:37.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.939791 32646 heartbeater.cc:507] Master 127.31.122.61:44761 requested a full tablet report, sending...
08:00:37.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.939502 32644 heartbeater.cc:507] Master 127.31.122.60:38193 requested a full tablet report, sending...
08:00:37.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.941210 32418 ts_manager.cc:194] Registered new tserver with Master: f274fc0a97894d6ca422e2622ba3e2f9 (127.31.122.1:46813)
08:00:37.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.941241 32346 ts_manager.cc:194] Registered new tserver with Master: f274fc0a97894d6ca422e2622ba3e2f9 (127.31.122.1:46813)
08:00:37.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.942399 32418 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.31.122.1:42797
08:00:37.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:37.942540 32273 ts_manager.cc:194] Registered new tserver with Master: f274fc0a97894d6ca422e2622ba3e2f9 (127.31.122.1:46813)
08:00:38.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.044090 32654 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:00:38.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.044305 32654 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:00:38.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.044348 32654 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:00:38.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.047797 32654 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:00:38.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.047909 32654 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.122.2
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.051802 32654 tablet_server_runner.cc:78] Tablet server non-default flags:
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/wal
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.2:0
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data/info.pb
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.2
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.32654
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.122.2
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/logs
08:00:38.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 07:43:13 UTC on bdcb31816ec0
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12768
08:00:38.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.052903 32654 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:00:38.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.054133 32654 file_cache.cc:504] Constructed file cache file cache with capacity 419430
08:00:38.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.060750 32659 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
08:00:38.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.060808 32654 server_base.cc:1061] running on GCE node
08:00:38.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.060740 32660 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
08:00:38.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.060981 32662 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
08:00:38.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.061362 32654 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:00:38.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.061939 32654 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:00:38.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.063110 32654 hybrid_clock.cc:648] HybridClock initialized: now 1783497638063094 us; error 33 us; skew 500 ppm
08:00:38.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.065105 32654 webserver.cc:533] Webserver started at http://127.31.122.2:42679/ using document root <none> and password file <none>
08:00:38.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.065714 32654 fs_manager.cc:362] Metadata directory not provided
08:00:38.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.065776 32654 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:00:38.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.066028 32654 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:00:38.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.067760 32654 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data/instance:
08:00:38.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d2781d750c804ba0932a69e02fa2bb29"
08:00:38.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:38 on dist-test-slave-kkww"
08:00:38.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.068298 32654 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/wal/instance:
08:00:38.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d2781d750c804ba0932a69e02fa2bb29"
08:00:38.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:38 on dist-test-slave-kkww"
08:00:38.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.071756 32654 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.003s	sys 0.001s
08:00:38.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.074007 32668 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:00:38.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.075114 32654 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
08:00:38.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.075238 32654 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data,/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/wal
08:00:38.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d2781d750c804ba0932a69e02fa2bb29"
08:00:38.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:38 on dist-test-slave-kkww"
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.075356 32654 fs_report.cc:389] FS layout report
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/wal
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/wal
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data/data
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:00:38.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:00:38.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:00:38.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:00:38.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:00:38.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:00:38.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.101694 32654 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:00:38.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.102489 32654 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:00:38.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.102674 32654 kserver.cc:163] Server-wide thread pool size limit: 3276
08:00:38.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.103338 32654 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
08:00:38.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.104629 32654 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:00:38.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.104676 32654 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
08:00:38.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.104729 32654 ts_tablet_manager.cc:616] Registered 0 tablets
08:00:38.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.104750 32654 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:00:38.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.113533 32654 rpc_server.cc:307] RPC server started. Bound to: 127.31.122.2:36591
08:00:38.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.113561   313 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.122.2:36591 every 8 connection(s)
08:00:38.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.114591 32654 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/data/info.pb
08:00:38.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.122488 32232 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu as pid 32654
08:00:38.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.122593 32232 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-1/wal/instance
08:00:38.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.124146   314 heartbeater.cc:344] Connected to a master server at 127.31.122.60:38193
08:00:38.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.124177   316 heartbeater.cc:344] Connected to a master server at 127.31.122.61:44761
08:00:38.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.124307   315 heartbeater.cc:344] Connected to a master server at 127.31.122.62:46745
08:00:38.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.124429   315 heartbeater.cc:461] Registering TS with master...
08:00:38.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.124430   316 heartbeater.cc:461] Registering TS with master...
08:00:38.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.124432   314 heartbeater.cc:461] Registering TS with master...
08:00:38.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.125003   314 heartbeater.cc:507] Master 127.31.122.60:38193 requested a full tablet report, sending...
08:00:38.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.125008   315 heartbeater.cc:507] Master 127.31.122.62:46745 requested a full tablet report, sending...
08:00:38.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.125015   316 heartbeater.cc:507] Master 127.31.122.61:44761 requested a full tablet report, sending...
08:00:38.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.126082 32273 ts_manager.cc:194] Registered new tserver with Master: d2781d750c804ba0932a69e02fa2bb29 (127.31.122.2:36591)
08:00:38.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.126111 32345 ts_manager.cc:194] Registered new tserver with Master: d2781d750c804ba0932a69e02fa2bb29 (127.31.122.2:36591)
08:00:38.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.126547 32418 ts_manager.cc:194] Registered new tserver with Master: d2781d750c804ba0932a69e02fa2bb29 (127.31.122.2:36591)
08:00:38.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.126685 32232 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:38.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu
08:00:38.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/wal
08:00:38.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/logs
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data/info.pb
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.3:0
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.122.3
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.3
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:38.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:38.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:38.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:38.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:38.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
08:00:38.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.127135 32418 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.31.122.2:56195
08:00:38.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.230347   321 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:00:38.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.230584   321 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:00:38.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.230645   321 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:00:38.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.234208   321 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:00:38.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.234345   321 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.122.3
08:00:38.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.238602   321 tablet_server_runner.cc:78] Tablet server non-default flags:
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.122.20:38789
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/wal
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.122.3:0
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data/info.pb
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.122.3
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.122.62:46745,127.31.122.61:44761,127.31.122.60:38193
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.321
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:00:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.122.3
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/logs
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 07:43:13 UTC on bdcb31816ec0
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12768
08:00:38.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.240140   321 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:00:38.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.241456   321 file_cache.cc:504] Constructed file cache file cache with capacity 419430
08:00:38.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.247884   326 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
08:00:38.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.248068   321 server_base.cc:1061] running on GCE node
08:00:38.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.247949   327 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
08:00:38.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:38.247920   329 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
08:00:38.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.248739   321 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:00:38.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.249356   321 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:00:38.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.250516   321 hybrid_clock.cc:648] HybridClock initialized: now 1783497638250486 us; error 42 us; skew 500 ppm
08:00:38.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.252439   321 webserver.cc:533] Webserver started at http://127.31.122.3:33883/ using document root <none> and password file <none>
08:00:38.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.253055   321 fs_manager.cc:362] Metadata directory not provided
08:00:38.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.253125   321 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:00:38.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.253388   321 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:00:38.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.255189   321 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data/instance:
08:00:38.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c8053b03d7314bf0b9f53f0b88ad882f"
08:00:38.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:38 on dist-test-slave-kkww"
08:00:38.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.255872   321 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/wal/instance:
08:00:38.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c8053b03d7314bf0b9f53f0b88ad882f"
08:00:38.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:38 on dist-test-slave-kkww"
08:00:38.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.259737   321 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.002s	sys 0.001s
08:00:38.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.262290   335 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.263305   321 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.263403   321 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data,/tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/wal
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c8053b03d7314bf0b9f53f0b88ad882f"
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 08:00:38 on dist-test-slave-kkww"
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.263477   321 fs_report.cc:389] FS layout report
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/wal
08:00:38.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/wal
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data/data
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:00:38.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:00:38.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.293490   321 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:00:38.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.294148   321 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:00:38.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.294291   321 kserver.cc:163] Server-wide thread pool size limit: 3276
08:00:38.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.294845   321 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
08:00:38.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.295895   321 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:00:38.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.295943   321 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
08:00:38.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.295981   321 ts_tablet_manager.cc:616] Registered 0 tablets
08:00:38.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.295994   321 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:00:38.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.304716   321 rpc_server.cc:307] RPC server started. Bound to: 127.31.122.3:41045
08:00:38.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.304749   448 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.122.3:41045 every 8 connection(s)
08:00:38.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.305627   321 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/data/info.pb
08:00:38.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.312569 32232 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu as pid 321
08:00:38.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.312677 32232 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskqFsYDK/test-tmp/mini-kudu-cluster17523699137046570556/ts-2/wal/instance
08:00:38.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315003   450 heartbeater.cc:344] Connected to a master server at 127.31.122.62:46745
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315073   449 heartbeater.cc:344] Connected to a master server at 127.31.122.60:38193
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315234   449 heartbeater.cc:461] Registering TS with master...
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315233   450 heartbeater.cc:461] Registering TS with master...
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315573   451 heartbeater.cc:344] Connected to a master server at 127.31.122.61:44761
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315640   451 heartbeater.cc:461] Registering TS with master...
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315834   450 heartbeater.cc:507] Master 127.31.122.62:46745 requested a full tablet report, sending...
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315824   451 heartbeater.cc:507] Master 127.31.122.61:44761 requested a full tablet report, sending...
08:00:38.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.315824   449 heartbeater.cc:507] Master 127.31.122.60:38193 requested a full tablet report, sending...
08:00:38.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.316910 32346 ts_manager.cc:194] Registered new tserver with Master: c8053b03d7314bf0b9f53f0b88ad882f (127.31.122.3:41045)
08:00:38.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.321374 32418 ts_manager.cc:194] Registered new tserver with Master: c8053b03d7314bf0b9f53f0b88ad882f (127.31.122.3:41045)
08:00:38.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.321952 32418 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.31.122.3:56671
08:00:38.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.322445 32273 ts_manager.cc:194] Registered new tserver with Master: c8053b03d7314bf0b9f53f0b88ad882f (127.31.122.3:41045)
08:00:38.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.333841 32232 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
08:00:38.334 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
08:00:38.335 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

08:00:38.344 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.122.62"
      port: 46745
    }
    bound_http_address {
      host: "127.31.122.62"
      port: 38191
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.31.122.61"
      port: 44761
    }
    bound_http_address {
      host: "127.31.122.61"
      port: 42843
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.31.122.60"
      port: 38193
    }
    bound_http_address {
      host: "127.31.122.60"
      port: 35675
    }
  }
}

08:00:38.347 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

08:00:38.349 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.122.1"
      port: 46813
    }
    bound_http_address {
      host: "127.31.122.1"
      port: 42007
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.31.122.2"
      port: 36591
    }
    bound_http_address {
      host: "127.31.122.2"
      port: 42679
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.31.122.3"
      port: 41045
    }
    bound_http_address {
      host: "127.31.122.3"
      port: 33883
    }
  }
}

08:00:38.349 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
08:00:38.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.400933 32488 catalog_manager.cc:1295] Loaded cluster ID: 273e46b6ca1444d7b708f126be6f66b2
08:00:38.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.401018 32488 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: loading cluster ID for follower catalog manager: success
08:00:38.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.403362 32488 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: acquiring CA information for follower catalog manager: success
08:00:38.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.404865 32488 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 16d20124b52e40c4a392d94dbacda5d9: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
08:00:38.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.441730 32502 catalog_manager.cc:1295] Loaded cluster ID: 273e46b6ca1444d7b708f126be6f66b2
08:00:38.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.441812 32502 catalog_manager.cc:1588] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: loading cluster ID for follower catalog manager: success
08:00:38.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.444723 32502 catalog_manager.cc:1610] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: acquiring CA information for follower catalog manager: success
08:00:38.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.446329 32502 catalog_manager.cc:1638] T 00000000000000000000000000000000 P d556d31bf22e49d8bfc2debaa78f9aa7: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
08:00:38.506 [DEBUG - main] (CompositeStrategy.java:59) Unable to load modern Subject methods, relying only on legacy methods
java.lang.NoSuchMethodException: javax.security.auth.Subject.current()
	at java.base/java.lang.Class.getDeclaredMethod(Class.java:2677)
	at org.apache.kudu.client.internals.ModernStrategy.<init>(ModernStrategy.java:51)
	at org.apache.kudu.client.internals.CompositeStrategy.<init>(CompositeStrategy.java:52)
	at org.apache.kudu.client.internals.CompositeStrategy.<clinit>(CompositeStrategy.java:41)
	at org.apache.kudu.client.internals.SecurityManagerCompatibility.get(SecurityManagerCompatibility.java:43)
	at org.apache.kudu.client.SecurityContext.setupSubject(SecurityContext.java:155)
	at org.apache.kudu.client.SecurityContext.<init>(SecurityContext.java:138)
	at org.apache.kudu.client.AsyncKuduClient.<init>(AsyncKuduClient.java:431)
	at org.apache.kudu.client.AsyncKuduClient$AsyncKuduClientBuilder.build(AsyncKuduClient.java:3109)
	at org.apache.kudu.test.KuduTestHarness.before(KuduTestHarness.java:161)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:50)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.doOneAttemptAndReport(RetryRule.java:184)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:244)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.runner.JUnitCore.runMain(JUnitCore.java:77)
	at org.junit.runner.JUnitCore.main(JUnitCore.java:36)
08:00:38.561 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
08:00:38.705 [DEBUG - main] (Connection.java:748) [peer master-127.31.122.62:46745(127.31.122.62:46745)] connecting to peer
08:00:38.755 [DEBUG - main] (Connection.java:748) [peer master-127.31.122.61:44761(127.31.122.61:44761)] connecting to peer
08:00:38.757 [DEBUG - main] (Connection.java:748) [peer master-127.31.122.60:38193(127.31.122.60:38193)] connecting to peer
08:00:38.772 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.31.122.60:38193(127.31.122.60:38193)] Successfully connected to peer
08:00:38.772 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.31.122.61:44761(127.31.122.61:44761)] Successfully connected to peer
08:00:38.772 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.31.122.62:46745(127.31.122.62:46745)] Successfully connected to peer
08:00:38.855 [DEBUG - kudu-nio-0] (Negotiator.java:528) SASL mechanism PLAIN chosen for peer 127.31.122.62
08:00:38.855 [DEBUG - kudu-nio-2] (Negotiator.java:528) SASL mechanism PLAIN chosen for peer 127.31.122.60
08:00:38.860 [DEBUG - kudu-nio-1] (Negotiator.java:528) SASL mechanism PLAIN chosen for peer 127.31.122.61
08:00:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:38.944228 32644 heartbeater.cc:499] Master 127.31.122.60:38193 was elected leader, sending a full tablet report...
08:00:39.070 [DEBUG - kudu-nio-2] (Negotiator.java:918) Authenticated connection [id: 0xa43206e3, L:/127.0.0.1:56882 - R:/127.31.122.60:38193] using SASL/PLAIN
08:00:39.070 [DEBUG - kudu-nio-0] (Negotiator.java:918) Authenticated connection [id: 0x60ab5630, L:/127.0.0.1:57694 - R:/127.31.122.62:46745] using SASL/PLAIN
08:00:39.070 [DEBUG - kudu-nio-1] (Negotiator.java:918) Authenticated connection [id: 0xc1485114, L:/127.0.0.1:39738 - R:/127.31.122.61:44761] using SASL/PLAIN
08:00:39.105 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
08:00:39.106 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=439), Traces: [0ms] refreshing cache from master, [25ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.122.62:46745, [86ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.122.61:44761, [88ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.122.60:38193, [415ms] Sub RPC ConnectToMaster: received response from server master-127.31.122.61:44761: OK, [415ms] Sub RPC ConnectToMaster: received response from server master-127.31.122.60:38193: OK, [415ms] Sub RPC ConnectToMaster: received response from server master-127.31.122.62:46745: OK, deferred=null) after lookup
08:00:39.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.128715   314 heartbeater.cc:499] Master 127.31.122.60:38193 was elected leader, sending a full tablet report...
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.152130 32418 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:56882:
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1783497636310"
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
08:00:39.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
08:00:39.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
08:00:39.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
08:00:39.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
08:00:39.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:00:39.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
08:00:39.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:39.153481 32418 catalog_manager.cc:7077] 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-1783497636310 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
08:00:39.184 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:894) Opened table 0feaaa34549e4fc0af6782be28cef3ec
08:00:39.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.192029 32716 tablet_service.cc:1511] Processing CreateTablet for tablet deef7bcbfc9f4066b111c8e623a14318 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783497636310 [id=0feaaa34549e4fc0af6782be28cef3ec]), partition=RANGE (key) PARTITION UNBOUNDED
08:00:39.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.192029   383 tablet_service.cc:1511] Processing CreateTablet for tablet deef7bcbfc9f4066b111c8e623a14318 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783497636310 [id=0feaaa34549e4fc0af6782be28cef3ec]), partition=RANGE (key) PARTITION UNBOUNDED
08:00:39.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.193073 32716 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet deef7bcbfc9f4066b111c8e623a14318. 1 dirs total, 0 dirs full, 0 dirs failed
08:00:39.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.193073   383 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet deef7bcbfc9f4066b111c8e623a14318. 1 dirs total, 0 dirs full, 0 dirs failed
08:00:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.197978 32578 tablet_service.cc:1511] Processing CreateTablet for tablet deef7bcbfc9f4066b111c8e623a14318 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783497636310 [id=0feaaa34549e4fc0af6782be28cef3ec]), partition=RANGE (key) PARTITION UNBOUNDED
08:00:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.199011 32578 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet deef7bcbfc9f4066b111c8e623a14318. 1 dirs total, 0 dirs full, 0 dirs failed
08:00:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.201781   477 tablet_bootstrap.cc:492] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29: Bootstrap starting.
08:00:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.202065   478 tablet_bootstrap.cc:492] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f: Bootstrap starting.
08:00:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.204481   478 tablet_bootstrap.cc:654] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f: Neither blocks nor log segments found. Creating new log.
08:00:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.204495   477 tablet_bootstrap.cc:654] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29: Neither blocks nor log segments found. Creating new log.
08:00:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.205367   477 log.cc:826] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29: Log is configured to *not* fsync() on all Append() calls
08:00:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.205534   478 log.cc:826] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f: Log is configured to *not* fsync() on all Append() calls
08:00:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.205745   479 tablet_bootstrap.cc:492] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9: Bootstrap starting.
08:00:39.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.207340   478 tablet_bootstrap.cc:492] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f: No bootstrap required, opened a new log
08:00:39.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.207584   478 ts_tablet_manager.cc:1403] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f: Time spent bootstrapping tablet: real 0.006s	user 0.005s	sys 0.000s
08:00:39.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.208599   479 tablet_bootstrap.cc:654] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9: Neither blocks nor log segments found. Creating new log.
08:00:39.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.209571   479 log.cc:826] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9: Log is configured to *not* fsync() on all Append() calls
08:00:39.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.210683   478 raft_consensus.cc:359] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.210903   478 raft_consensus.cc:385] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:00:39.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.210970   478 raft_consensus.cc:740] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c8053b03d7314bf0b9f53f0b88ad882f, State: Initialized, Role: FOLLOWER
08:00:39.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.211740   478 consensus_queue.cc:260] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [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: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.212075   479 tablet_bootstrap.cc:492] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9: No bootstrap required, opened a new log
08:00:39.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.212791   478 ts_tablet_manager.cc:1434] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f: Time spent starting tablet: real 0.005s	user 0.004s	sys 0.000s
08:00:39.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.213356   449 heartbeater.cc:499] Master 127.31.122.60:38193 was elected leader, sending a full tablet report...
08:00:39.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.216091   479 ts_tablet_manager.cc:1403] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9: Time spent bootstrapping tablet: real 0.010s	user 0.005s	sys 0.000s
08:00:39.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.219604   479 raft_consensus.cc:359] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.220046   479 raft_consensus.cc:385] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:00:39.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.220261   479 raft_consensus.cc:740] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: f274fc0a97894d6ca422e2622ba3e2f9, State: Initialized, Role: FOLLOWER
08:00:39.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.220417   477 tablet_bootstrap.cc:492] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29: No bootstrap required, opened a new log
08:00:39.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.220764   477 ts_tablet_manager.cc:1403] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29: Time spent bootstrapping tablet: real 0.019s	user 0.006s	sys 0.000s
08:00:39.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.221208   479 consensus_queue.cc:260] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [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: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.222718   479 ts_tablet_manager.cc:1434] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9: Time spent starting tablet: real 0.007s	user 0.005s	sys 0.000s
08:00:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.224593   477 raft_consensus.cc:359] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.225046   477 raft_consensus.cc:385] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:00:39.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.225270   477 raft_consensus.cc:740] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d2781d750c804ba0932a69e02fa2bb29, State: Initialized, Role: FOLLOWER
08:00:39.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.225981   477 consensus_queue.cc:260] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [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: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.227104   477 ts_tablet_manager.cc:1434] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29: Time spent starting tablet: real 0.006s	user 0.005s	sys 0.000s
08:00:39.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.237748   483 raft_consensus.cc:493] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
08:00:39.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.237924   483 raft_consensus.cc:515] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.238983   483 leader_election.cc:290] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers f274fc0a97894d6ca422e2622ba3e2f9 (127.31.122.1:46813), d2781d750c804ba0932a69e02fa2bb29 (127.31.122.2:36591)
08:00:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.242839 32736 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "deef7bcbfc9f4066b111c8e623a14318" candidate_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d2781d750c804ba0932a69e02fa2bb29" is_pre_election: true
08:00:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.242841 32598 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "deef7bcbfc9f4066b111c8e623a14318" candidate_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" is_pre_election: true
08:00:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.243081 32598 raft_consensus.cc:2468] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate c8053b03d7314bf0b9f53f0b88ad882f in term 0.
08:00:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.243089 32736 raft_consensus.cc:2468] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate c8053b03d7314bf0b9f53f0b88ad882f in term 0.
08:00:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.243549   337 leader_election.cc:304] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [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: c8053b03d7314bf0b9f53f0b88ad882f, f274fc0a97894d6ca422e2622ba3e2f9; no voters: 
08:00:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.243783   483 raft_consensus.cc:2804] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Leader pre-election won for term 1
08:00:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.243851   483 raft_consensus.cc:493] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
08:00:39.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.243891   483 raft_consensus.cc:3060] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 0 FOLLOWER]: Advancing to term 1
08:00:39.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.244740   483 raft_consensus.cc:515] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.245082   483 leader_election.cc:290] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [CANDIDATE]: Term 1 election: Requested vote from peers f274fc0a97894d6ca422e2622ba3e2f9 (127.31.122.1:46813), d2781d750c804ba0932a69e02fa2bb29 (127.31.122.2:36591)
08:00:39.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.245437 32736 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "deef7bcbfc9f4066b111c8e623a14318" candidate_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d2781d750c804ba0932a69e02fa2bb29"
08:00:39.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.245434 32598 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "deef7bcbfc9f4066b111c8e623a14318" candidate_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "f274fc0a97894d6ca422e2622ba3e2f9"
08:00:39.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.245563 32598 raft_consensus.cc:3060] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [term 0 FOLLOWER]: Advancing to term 1
08:00:39.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.245563 32736 raft_consensus.cc:3060] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [term 0 FOLLOWER]: Advancing to term 1
08:00:39.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.246629 32736 raft_consensus.cc:2468] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate c8053b03d7314bf0b9f53f0b88ad882f in term 1.
08:00:39.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.246629 32598 raft_consensus.cc:2468] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate c8053b03d7314bf0b9f53f0b88ad882f in term 1.
08:00:39.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.246977   337 leader_election.cc:304] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [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: c8053b03d7314bf0b9f53f0b88ad882f, f274fc0a97894d6ca422e2622ba3e2f9; no voters: 
08:00:39.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.247241   483 raft_consensus.cc:2804] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 1 FOLLOWER]: Leader election won for term 1
08:00:39.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.247493   483 raft_consensus.cc:697] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [term 1 LEADER]: Becoming Leader. State: Replica: c8053b03d7314bf0b9f53f0b88ad882f, State: Running, Role: LEADER
08:00:39.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.247849   483 consensus_queue.cc:237] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [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: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } }
08:00:39.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.251746 32417 catalog_manager.cc:5697] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f reported cstate change: term changed from 0 to 1, leader changed from <none> to c8053b03d7314bf0b9f53f0b88ad882f (127.31.122.3). New cstate: current_term: 1 leader_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8053b03d7314bf0b9f53f0b88ad882f" member_type: VOTER last_known_addr { host: "127.31.122.3" port: 41045 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 } health_report { overall_health: UNKNOWN } } }
08:00:39.288 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet deef7bcbfc9f4066b111c8e623a14318 for table 'org.apache.kudu.client.TestHybridTime-1783497636310' with partition [<start>, <end>)
08:00:39.293 [DEBUG - kudu-nio-2] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=deef7bcbfc9f4066b111c8e623a14318@[c8053b03d7314bf0b9f53f0b88ad882f(127.31.122.3:41045)[L],d2781d750c804ba0932a69e02fa2bb29(127.31.122.2:36591),f274fc0a97894d6ca422e2622ba3e2f9(127.31.122.1:46813)]}]
08:00:39.295 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=16), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.31.122.60:38193, [8ms] Sub RPC GetTableLocations: received response from server master-127.31.122.60:38193: OK, deferred=null) after lookup
08:00:39.304 [DEBUG - kudu-nio-2] (Connection.java:748) [peer c8053b03d7314bf0b9f53f0b88ad882f(127.31.122.3:41045)] connecting to peer
08:00:39.306 [DEBUG - kudu-nio-3] (Connection.java:757) [peer c8053b03d7314bf0b9f53f0b88ad882f(127.31.122.3:41045)] Successfully connected to peer
08:00:39.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:39.307826   452 tablet.cc:2406] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:00:39.367 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 08:00:39.366888   317 tablet.cc:2406] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:00:39.382 [DEBUG - kudu-nio-3] (Negotiator.java:918) Authenticated connection [id: 0x107f35af, L:/127.0.0.1:34344 - R:/127.31.122.3:41045] using TOKEN/null
08:00:39.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.390364 32736 raft_consensus.cc:1275] T deef7bcbfc9f4066b111c8e623a14318 P d2781d750c804ba0932a69e02fa2bb29 [term 1 FOLLOWER]: Refusing update from remote peer c8053b03d7314bf0b9f53f0b88ad882f: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
08:00:39.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.390394 32598 raft_consensus.cc:1275] T deef7bcbfc9f4066b111c8e623a14318 P f274fc0a97894d6ca422e2622ba3e2f9 [term 1 FOLLOWER]: Refusing update from remote peer c8053b03d7314bf0b9f53f0b88ad882f: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
08:00:39.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.391065   488 consensus_queue.cc:1048] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [LEADER]: Connected to new peer: Peer: permanent_uuid: "d2781d750c804ba0932a69e02fa2bb29" member_type: VOTER last_known_addr { host: "127.31.122.2" port: 36591 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
08:00:39.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.391577   488 consensus_queue.cc:1048] T deef7bcbfc9f4066b111c8e623a14318 P c8053b03d7314bf0b9f53f0b88ad882f [LEADER]: Connected to new peer: Peer: permanent_uuid: "f274fc0a97894d6ca422e2622ba3e2f9" member_type: VOTER last_known_addr { host: "127.31.122.1" port: 46813 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
08:00:39.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.400038   493 mvcc.cc:204] Tried to move back new op lower bound from 7305206330935025664 to 7305206330362408960. Current Snapshot: MvccSnapshot[applied={T|T < 7305206330935025664}]
08:00:39.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.403309   495 mvcc.cc:204] Tried to move back new op lower bound from 7305206330935025664 to 7305206330362408960. Current Snapshot: MvccSnapshot[applied={T|T < 7305206330935025664}]
08:00:39.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.410930   494 mvcc.cc:204] Tried to move back new op lower bound from 7305206330935025664 to 7305206330362408960. Current Snapshot: MvccSnapshot[applied={T|T < 7305206330935025664}]
08:00:39.443 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
08:00:39.456 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
08:00:39.463 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
08:00:39.476 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1172377039(state=PENDING, result=null, callback=<none>, errback=<none>)}
08:00:39.486 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
08:00:39.487 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1950850040(state=PENDING, result=null, callback=<none>, errback=<none>)}
08:00:39.494 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
08:00:39.495 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1657451779(state=PENDING, result=null, callback=<none>, errback=<none>)}
08:00:39.505 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
08:00:39.549 [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-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.555 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7305206351415025665) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.560 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7305206351415025669) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.564 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7305206351415025673) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.570 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7305206351415025677) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.574 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7305206351415025681) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.578 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7305206351415025685) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.582 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7305206351415025686) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783497636310, tablet=deef7bcbfc9f4066b111c8e623a14318, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:00:39.586 [DEBUG - main] (Connection.java:575) [peer master-127.31.122.61:44761(127.31.122.61:44761)] disconnecting while in state READY
08:00:39.587 [DEBUG - main] (Connection.java:575) [peer master-127.31.122.62:46745(127.31.122.62:46745)] disconnecting while in state READY
08:00:39.587 [DEBUG - main] (Connection.java:575) [peer c8053b03d7314bf0b9f53f0b88ad882f(127.31.122.3:41045)] disconnecting while in state READY
08:00:39.587 [DEBUG - main] (Connection.java:575) [peer master-127.31.122.60:38193(127.31.122.60:38193)] disconnecting while in state READY
08:00:39.591 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.31.122.61:44761(127.31.122.61:44761)] handling channelInactive
08:00:39.591 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.31.122.62:46745(127.31.122.62:46745)] handling channelInactive
08:00:39.592 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.31.122.60:38193(127.31.122.60:38193)] handling channelInactive
08:00:39.592 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.31.122.62:46745(127.31.122.62:46745)] cleaning up while in state READY due to: connection closed
08:00:39.592 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.31.122.60:38193(127.31.122.60:38193)] cleaning up while in state READY due to: connection closed
08:00:39.592 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.31.122.61:44761(127.31.122.61:44761)] cleaning up while in state READY due to: connection closed
08:00:39.593 [DEBUG - kudu-nio-3] (AsyncKuduClient.java:2704) Releasing all remaining resources
08:00:39.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.603727 32232 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu with pid 32516
08:00:39.608 [DEBUG - kudu-nio-3] (Connection.java:239) [peer c8053b03d7314bf0b9f53f0b88ad882f(127.31.122.3:41045)] handling channelInactive
08:00:39.608 [DEBUG - kudu-nio-3] (Connection.java:709) [peer c8053b03d7314bf0b9f53f0b88ad882f(127.31.122.3:41045)] cleaning up while in state READY due to: connection closed
08:00:39.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.612615 32232 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu with pid 32654
08:00:39.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.619951 32232 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu with pid 321
08:00:39.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.629020 32232 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu with pid 32243
08:00:39.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.634451 32232 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu with pid 32312
08:00:39.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 08:00:39.639657 32232 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskqFsYDK/build/debug/bin/kudu with pid 32384
08:00:39.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T08:00:39Z chronyd exiting

Time: 3.494

OK (1 test)

