JUnit version 4.13.2
.SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/tmp/dist-test-taskOA3nPh/java/build/jars/log4j-slf4j-impl-2.17.1.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/tmp/dist-test-taskOA3nPh/java/build/jars/slf4j-log4j12-1.7.30.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory]
18:51:14.816 [INFO - main] (RandomUtils.java:49) Using random seed: 1786474274772
18:51:14.845 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
18:51:14.913 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
18:51:14.922 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskOA3nPh/test-tmp
18:51:14.924 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/release/bin
18:51:14.927 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskOA3nPh/java/build/dist-test/../../../build/release/bin/kudu, test, mini_cluster, --serialization=pb]
18:51:14.959 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

18:51:15.167 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
18:51:15.176 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

18:51:15.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.177785  2944 mini_oidc.cc:152] Starting JWKS server
18:51:15.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.178905  2944 webserver.cc:460] Webserver started at http://127.0.0.1:33917/ using document root <none> and password file <none>
18:51:15.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.179054  2944 mini_oidc.cc:189] Starting OIDC Discovery server
18:51:15.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.179350  2944 webserver.cc:460] Webserver started at http://127.0.0.1:33117/ using document root <none> and password file <none>
18:51:15.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T18:51:15Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
18:51:15.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T18:51:15Z Disabled control of system clock
18:51:15.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.198697  2944 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/wal
18:51:15.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/logs
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data/info.pb
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:51:15.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:51:15.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:51:15.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:51:15.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.62:35467
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.62
18:51:15.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:51:15.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33917/jwks.json with env {}
18:51:15.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.291278  2957 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
18:51:15.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.291677  2957 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:51:15.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.291733  2957 flags.cc:425] Enabled unsafe flag: --never_fsync=true
18:51:15.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.293237  2957 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
18:51:15.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.293309  2957 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
18:51:15.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.293325  2957 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
18:51:15.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.293339  2957 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
18:51:15.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.293352  2957 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
18:51:15.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.293363  2957 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:33917/jwks.json
18:51:15.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.295159  2957 master_runner.cc:386] Master server non-default flags:
18:51:15.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:15.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:15.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:15.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:15.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data
18:51:15.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/wal
18:51:15.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:51:15.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:15.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.62:35467
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33917/jwks.json
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:15.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data/info.pb
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.62
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.2957
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/logs
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:15.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 17:59:43 UTC on ccycloud.quasar-xuakos.root.comops.site
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581133
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.295437  2957 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:51:15.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.295713  2957 file_cache.cc:493] Constructed file cache file cache with capacity 419430
18:51:15.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.302855  2963 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
18:51:15.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.302811  2962 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
18:51:15.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.302811  2965 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
18:51:15.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.303016  2957 server_base.cc:1034] running on GCE node
18:51:15.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.303401  2957 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:51:15.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.303810  2957 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:51:15.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.304999  2957 hybrid_clock.cc:648] HybridClock initialized: now 1786474275305006 us; error 147 us; skew 500 ppm
18:51:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.311663  2957 webserver.cc:460] Webserver started at http://127.2.224.62:45821/ using document root <none> and password file <none>
18:51:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.312055  2957 fs_manager.cc:359] Metadata directory not provided
18:51:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.312134  2957 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:51:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.312289  2957 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
18:51:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.313652  2957 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data/instance:
18:51:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4bf303845aae459e8c299e56ae635eff"
18:51:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.314136  2957 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/wal/instance:
18:51:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4bf303845aae459e8c299e56ae635eff"
18:51:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.316145  2957 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
18:51:15.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.317308  2973 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:51:15.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.317695  2957 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
18:51:15.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.317813  2957 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data,/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/wal
18:51:15.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4bf303845aae459e8c299e56ae635eff"
18:51:15.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.317903  2957 fs_report.cc:383] FS layout report
18:51:15.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:51:15.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/wal
18:51:15.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/wal
18:51:15.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data/data
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:51:15.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:51:15.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:51:15.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:51:15.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.354825  2957 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
18:51:15.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.355273  2957 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:51:15.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.355441  2957 kserver.cc:163] Server-wide thread pool size limit: 3276
18:51:15.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.361019  2957 rpc_server.cc:307] RPC server started. Bound to: 127.2.224.62:35467
18:51:15.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.361083  3038 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.2.224.62:35467 every 8 connection(s)
18:51:15.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.361543  2957 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/data/info.pb
18:51:15.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.362874  3040 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
18:51:15.367 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.366578  3040 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.369431  2944 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskOA3nPh/build/release/bin/kudu as pid 2957
18:51:15.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.369626  2944 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-0/wal/instance
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.370759  2944 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/wal
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/logs
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data/info.pb
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:51:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:51:15.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:51:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:51:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.61:45759
18:51:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.61
18:51:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:15.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:15.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:51:15.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:15.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:51:15.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33917/jwks.json with env {}
18:51:15.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.377561  3040 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.378415  2975 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.2.224.61:45759: connect: Connection refused (error 111)
18:51:15.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.379385  3040 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.61:45759: Network error: Client connection negotiation failed: client connection to 127.2.224.61:45759: connect: Connection refused (error 111)
18:51:15.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.428714  3040 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } attempt: 1
18:51:15.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.429486  3040 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.61:45759: Network error: Client connection negotiation failed: client connection to 127.2.224.61:45759: connect: Connection refused (error 111)
18:51:15.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.482715  3046 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
18:51:15.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.482975  3046 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:51:15.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.482998  3046 flags.cc:425] Enabled unsafe flag: --never_fsync=true
18:51:15.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.484552  3046 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
18:51:15.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.484623  3046 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
18:51:15.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.484638  3046 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
18:51:15.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.484651  3046 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
18:51:15.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.484665  3046 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
18:51:15.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.484676  3046 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:33917/jwks.json
18:51:15.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.487030  3046 master_runner.cc:386] Master server non-default flags:
18:51:15.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:15.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:15.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:15.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:15.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data
18:51:15.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/wal
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.61:45759
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33917/jwks.json
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data/info.pb
18:51:15.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.61
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.3046
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/logs
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:15.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:51:15.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:51:15.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
18:51:15.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
18:51:15.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
18:51:15.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
18:51:15.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 17:59:43 UTC on ccycloud.quasar-xuakos.root.comops.site
18:51:15.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581133
18:51:15.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.487408  3046 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:51:15.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.487800  3046 file_cache.cc:493] Constructed file cache file cache with capacity 419430
18:51:15.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.494735  3053 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
18:51:15.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.494951  3055 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
18:51:15.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.494925  3046 server_base.cc:1034] running on GCE node
18:51:15.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.494930  3052 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
18:51:15.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.496769  3046 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:51:15.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.497252  3046 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:51:15.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.497656  3040 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } attempt: 2
18:51:15.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.498459  3046 hybrid_clock.cc:648] HybridClock initialized: now 1786474275498435 us; error 54 us; skew 500 ppm
18:51:15.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.498673  3040 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.61:45759: Network error: Client connection negotiation failed: client connection to 127.2.224.61:45759: connect: Connection refused (error 111)
18:51:15.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.500461  3046 webserver.cc:460] Webserver started at http://127.2.224.61:44907/ using document root <none> and password file <none>
18:51:15.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.500837  3046 fs_manager.cc:359] Metadata directory not provided
18:51:15.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.500905  3046 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:51:15.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.501176  3046 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
18:51:15.502 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.502362  3046 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data/instance:
18:51:15.502 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8c21386444124f58aa7b00a7bcb81606"
18:51:15.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.502727  3046 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/wal/instance:
18:51:15.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8c21386444124f58aa7b00a7bcb81606"
18:51:15.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.504323  3046 fs_manager.cc:691] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
18:51:15.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.505311  3062 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:51:15.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.505649  3046 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
18:51:15.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.505769  3046 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data,/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/wal
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8c21386444124f58aa7b00a7bcb81606"
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.505901  3046 fs_report.cc:383] FS layout report
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/wal
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/wal
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data/data
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:51:15.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:51:15.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:51:15.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.522861  3046 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
18:51:15.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.523291  3046 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:51:15.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.523509  3046 kserver.cc:163] Server-wide thread pool size limit: 3276
18:51:15.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.531036  3046 rpc_server.cc:307] RPC server started. Bound to: 127.2.224.61:45759
18:51:15.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.531208  3124 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.2.224.61:45759 every 8 connection(s)
18:51:15.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.531699  3046 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/data/info.pb
18:51:15.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.532652  3125 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
18:51:15.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.535127  3125 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.539565  2944 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskOA3nPh/build/release/bin/kudu as pid 3046
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.539750  2944 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-1/wal/instance
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.540233  2944 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/wal
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:51:15.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/logs
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data/info.pb
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:15.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.60:38703
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.60
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:51:15.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:15.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:51:15.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33917/jwks.json with env {}
18:51:15.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.542790  3125 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.549640  3125 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.550431  3064 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.2.224.60:38703: connect: Connection refused (error 111)
18:51:15.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.551154  3125 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.60:38703: Network error: Client connection negotiation failed: client connection to 127.2.224.60:38703: connect: Connection refused (error 111)
18:51:15.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.589910  3040 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } attempt: 3
18:51:15.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.596160  3040 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.597229  3040 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.60:38703: Network error: Client connection negotiation failed: client connection to 127.2.224.60:38703: connect: Connection refused (error 111)
18:51:15.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.600445  3125 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } attempt: 1
18:51:15.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.601754  3125 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.60:38703: Network error: Client connection negotiation failed: client connection to 127.2.224.60:38703: connect: Connection refused (error 111)
18:51:15.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.628471  3040 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } attempt: 1
18:51:15.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.629518  3040 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.60:38703: Network error: Client connection negotiation failed: client connection to 127.2.224.60:38703: connect: Connection refused (error 111)
18:51:15.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.647770  3128 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
18:51:15.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.648029  3128 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:51:15.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.648054  3128 flags.cc:425] Enabled unsafe flag: --never_fsync=true
18:51:15.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.649328  3128 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
18:51:15.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.649382  3128 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
18:51:15.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.649397  3128 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
18:51:15.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.649410  3128 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
18:51:15.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.649423  3128 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
18:51:15.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.649434  3128 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:33917/jwks.json
18:51:15.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.651365  3128 master_runner.cc:386] Master server non-default flags:
18:51:15.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:15.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:15.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:15.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:15.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data
18:51:15.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/wal
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.60:38703
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33917/jwks.json
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data/info.pb
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.60
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:51:15.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.3128
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/logs
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
18:51:15.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
18:51:15.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
18:51:15.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 17:59:43 UTC on ccycloud.quasar-xuakos.root.comops.site
18:51:15.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581133
18:51:15.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.651633  3128 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:51:15.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.652078  3128 file_cache.cc:493] Constructed file cache file cache with capacity 419430
18:51:15.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.659463  3137 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
18:51:15.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.659473  3138 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
18:51:15.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.659463  3140 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
18:51:15.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.660435  3128 server_base.cc:1034] running on GCE node
18:51:15.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.660831  3128 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:51:15.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.661218  3128 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:51:15.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.662456  3128 hybrid_clock.cc:648] HybridClock initialized: now 1786474275662441 us; error 55 us; skew 500 ppm
18:51:15.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.664336  3128 webserver.cc:460] Webserver started at http://127.2.224.60:37427/ using document root <none> and password file <none>
18:51:15.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.664642  3128 fs_manager.cc:359] Metadata directory not provided
18:51:15.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.664708  3128 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:51:15.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.664858  3128 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
18:51:15.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.666370  3128 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data/instance:
18:51:15.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "166d777cd3eb4aa1a9e212b62a83c917"
18:51:15.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.666903  3128 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/wal/instance:
18:51:15.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "166d777cd3eb4aa1a9e212b62a83c917"
18:51:15.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.668771  3128 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.001s
18:51:15.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.670022  3125 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } attempt: 2
18:51:15.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.670936  3125 consensus_peers.cc:646] Error getting permanent uuid from config peer 127.2.224.60:38703: Network error: Client connection negotiation failed: client connection to 127.2.224.60:38703: connect: Connection refused (error 111)
18:51:15.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.674098  3149 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:51:15.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.674577  3128 fs_manager.cc:725] Time spent opening block manager: real 0.005s	user 0.001s	sys 0.000s
18:51:15.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.674721  3128 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data,/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/wal
18:51:15.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "166d777cd3eb4aa1a9e212b62a83c917"
18:51:15.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:15 on dist-test-slave-g350"
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.674824  3128 fs_report.cc:383] FS layout report
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/wal
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/wal
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data/data
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:51:15.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:51:15.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:51:15.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:51:15.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:51:15.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.692168  3128 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
18:51:15.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.692534  3128 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:51:15.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.692716  3128 kserver.cc:163] Server-wide thread pool size limit: 3276
18:51:15.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.698520  3128 rpc_server.cc:307] RPC server started. Bound to: 127.2.224.60:38703
18:51:15.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.698755  3208 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.2.224.60:38703 every 8 connection(s)
18:51:15.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.699039  3128 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/data/info.pb
18:51:15.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.700327  3210 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
18:51:15.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.701058  2944 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskOA3nPh/build/release/bin/kudu as pid 3128
18:51:15.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.701171  2944 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/master-2/wal/instance
18:51:15.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.703063  3210 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.704840  3040 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } attempt: 2
18:51:15.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.710891  3210 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.712306  3040 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff: Bootstrap starting.
18:51:15.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.713358  3040 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff: Neither blocks nor log segments found. Creating new log.
18:51:15.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.714164  3040 log.cc:824] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff: Log is configured to *not* fsync() on all Append() calls
18:51:15.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.715433  3040 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff: No bootstrap required, opened a new log
18:51:15.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.716238  3210 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } has no permanent_uuid. Determining permanent_uuid...
18:51:15.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.718173  3040 raft_consensus.cc:348] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.718357  3040 raft_consensus.cc:374] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:51:15.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.718394  3040 raft_consensus.cc:729] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 4bf303845aae459e8c299e56ae635eff, State: Initialized, Role: FOLLOWER
18:51:15.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.718554  3040 consensus_queue.cc:260] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [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: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.718955  3040 sys_catalog.cc:564] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: configured and running, proceeding with master startup.
18:51:15.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.720077  3219 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [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: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.721203  3219 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: This master's current role is: FOLLOWER
18:51:15.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.723038  3231 catalog_manager.cc:1558] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
18:51:15.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.723383  3231 catalog_manager.cc:873] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
18:51:15.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.727217  3210 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: Bootstrap starting.
18:51:15.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.728091  3210 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: Neither blocks nor log segments found. Creating new log.
18:51:15.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.728454  3210 log.cc:824] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: Log is configured to *not* fsync() on all Append() calls
18:51:15.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.726179  2957 master_runner.cc:186] Error getting master registration for 127.2.224.61:45759: OK, instance_id { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" instance_seqno: 1786474275528633 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
18:51:15.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.729436  2957 master_runner.cc:418] Couldn't verify the masters in the cluster. Trying again...
18:51:15.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.734468  3210 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: No bootstrap required, opened a new log
18:51:15.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.737277  3210 raft_consensus.cc:348] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.737542  3210 raft_consensus.cc:374] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:51:15.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.737636  3210 raft_consensus.cc:729] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 166d777cd3eb4aa1a9e212b62a83c917, State: Initialized, Role: FOLLOWER
18:51:15.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.737823  3210 consensus_queue.cc:260] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [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: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.738152  3235 sys_catalog.cc:455] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [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: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.738231  3235 sys_catalog.cc:458] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [sys.catalog]: This master's current role is: FOLLOWER
18:51:15.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.738243  3210 sys_catalog.cc:564] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [sys.catalog]: configured and running, proceeding with master startup.
18:51:15.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.741112  3249 catalog_manager.cc:1558] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
18:51:15.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.741479  3249 catalog_manager.cc:873] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
18:51:15.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.742338  3128 master_runner.cc:186] Error getting master registration for 127.2.224.61:45759: OK, instance_id { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" instance_seqno: 1786474275528633 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
18:51:15.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.744479  3128 master_runner.cc:418] Couldn't verify the masters in the cluster. Trying again...
18:51:15.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.748421  3219 raft_consensus.cc:482] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
18:51:15.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.748611  3219 raft_consensus.cc:504] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.751802  3219 leader_election.cc:288] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 8c21386444124f58aa7b00a7bcb81606 (127.2.224.61:45759), 166d777cd3eb4aa1a9e212b62a83c917 (127.2.224.60:38703)
18:51:15.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.752218  3182 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4bf303845aae459e8c299e56ae635eff" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "166d777cd3eb4aa1a9e212b62a83c917" is_pre_election: true
18:51:15.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.752467  3182 raft_consensus.cc:2454] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 4bf303845aae459e8c299e56ae635eff in term 0.
18:51:15.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.752774  2975 leader_election.cc:302] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [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: 166d777cd3eb4aa1a9e212b62a83c917, 4bf303845aae459e8c299e56ae635eff; no voters: 
18:51:15.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.753010  3219 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Leader pre-election won for term 1
18:51:15.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.753805  3219 raft_consensus.cc:482] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
18:51:15.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.753930  3219 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 0 FOLLOWER]: Advancing to term 1
18:51:15.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.756048  3219 raft_consensus.cc:504] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.757079  3182 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4bf303845aae459e8c299e56ae635eff" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "166d777cd3eb4aa1a9e212b62a83c917"
18:51:15.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.757226  3182 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [term 0 FOLLOWER]: Advancing to term 1
18:51:15.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.758584  3182 raft_consensus.cc:2454] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 4bf303845aae459e8c299e56ae635eff in term 1.
18:51:15.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.758896  2975 leader_election.cc:302] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [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: 166d777cd3eb4aa1a9e212b62a83c917, 4bf303845aae459e8c299e56ae635eff; no voters: 
18:51:15.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.756635  3219 leader_election.cc:288] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [CANDIDATE]: Term 1 election: Requested vote from peers 8c21386444124f58aa7b00a7bcb81606 (127.2.224.61:45759), 166d777cd3eb4aa1a9e212b62a83c917 (127.2.224.60:38703)
18:51:15.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.760290  3219 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 1 FOLLOWER]: Leader election won for term 1
18:51:15.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.760362  3219 raft_consensus.cc:686] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [term 1 LEADER]: Becoming Leader. State: Replica: 4bf303845aae459e8c299e56ae635eff, State: Running, Role: LEADER
18:51:15.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.760483  3219 consensus_queue.cc:237] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [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: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.761091  3219 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4bf303845aae459e8c299e56ae635eff. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.761175  3219 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: This master's current role is: LEADER
18:51:15.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.762112  3125 consensus_peers.cc:656] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } attempt: 3
18:51:15.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.769280  3125 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: Bootstrap starting.
18:51:15.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.771030  3125 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: Neither blocks nor log segments found. Creating new log.
18:51:15.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.772017  3125 log.cc:824] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: Log is configured to *not* fsync() on all Append() calls
18:51:15.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.774329  3254 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
18:51:15.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.774469  3125 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: No bootstrap required, opened a new log
18:51:15.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.774570  3254 catalog_manager.cc:1484] Initializing Kudu cluster ID...
18:51:15.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.777787  3125 raft_consensus.cc:348] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.778403  3125 raft_consensus.cc:374] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:51:15.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.778671  3125 raft_consensus.cc:729] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8c21386444124f58aa7b00a7bcb81606, State: Initialized, Role: FOLLOWER
18:51:15.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.779129  3125 consensus_queue.cc:260] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [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: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } }
18:51:15.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.779973  3125 sys_catalog.cc:564] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [sys.catalog]: configured and running, proceeding with master startup.
18:51:15.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.780002  3096 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4bf303845aae459e8c299e56ae635eff" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "8c21386444124f58aa7b00a7bcb81606" is_pre_election: true
18:51:15.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.780468  3096 raft_consensus.cc:2454] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 4bf303845aae459e8c299e56ae635eff in term 0.
18:51:15.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.780133  3094 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4bf303845aae459e8c299e56ae635eff" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "8c21386444124f58aa7b00a7bcb81606"
18:51:15.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.780845  3094 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [term 0 FOLLOWER]: Advancing to term 1
18:51:15.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.782104  3094 raft_consensus.cc:2454] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 4bf303845aae459e8c299e56ae635eff in term 1.
18:51:15.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.787367  3182 raft_consensus.cc:1261] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [term 1 FOLLOWER]: Refusing update from remote peer 4bf303845aae459e8c299e56ae635eff: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
18:51:15.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.787773  3252 consensus_queue.cc:1035] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [LEADER]: Connected to new peer: Peer: permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
18:51:15.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.789919  3235 sys_catalog.cc:455] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4bf303845aae459e8c299e56ae635eff. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.790045  3235 sys_catalog.cc:458] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [sys.catalog]: This master's current role is: FOLLOWER
18:51:15.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.790696  3094 raft_consensus.cc:1261] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [term 1 FOLLOWER]: Refusing update from remote peer 4bf303845aae459e8c299e56ae635eff: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
18:51:15.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.791086  3252 consensus_queue.cc:1035] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [LEADER]: Connected to new peer: Peer: permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
18:51:15.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.793383  3270 catalog_manager.cc:1558] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
18:51:15.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:15.797648  3270 catalog_manager.cc:873] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
18:51:15.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.805884  3252 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.805845  3273 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.805997  3252 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: This master's current role is: LEADER
18:51:15.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.806002  3273 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [sys.catalog]: This master's current role is: FOLLOWER
18:51:15.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.806058  3273 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4bf303845aae459e8c299e56ae635eff. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.806111  3273 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [sys.catalog]: This master's current role is: FOLLOWER
18:51:15.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.806093  3256 mvcc.cc:205] Tried to move back new op lower bound from 7317398633621688320 to 7317398633515962368. Current Snapshot: MvccSnapshot[applied={T|T < 7317398633621688320}]
18:51:15.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.807588  3219 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.807713  3219 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff [sys.catalog]: This master's current role is: LEADER
18:51:15.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.808007  3235 sys_catalog.cc:455] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.808120  3235 sys_catalog.cc:458] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917 [sys.catalog]: This master's current role is: FOLLOWER
18:51:15.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.808257  3274 mvcc.cc:205] Tried to move back new op lower bound from 7317398633621688320 to 7317398633515962368. Current Snapshot: MvccSnapshot[applied={T|T < 7317398633621688320}]
18:51:15.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.810736  3271 mvcc.cc:205] Tried to move back new op lower bound from 7317398633621688320 to 7317398633515962368. Current Snapshot: MvccSnapshot[applied={T|T < 7317398633621688320}]
18:51:15.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.817492  3257 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "4bf303845aae459e8c299e56ae635eff" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "4bf303845aae459e8c299e56ae635eff" member_type: VOTER last_known_addr { host: "127.2.224.62" port: 35467 } } peers { permanent_uuid: "8c21386444124f58aa7b00a7bcb81606" member_type: VOTER last_known_addr { host: "127.2.224.61" port: 45759 } } peers { permanent_uuid: "166d777cd3eb4aa1a9e212b62a83c917" member_type: VOTER last_known_addr { host: "127.2.224.60" port: 38703 } } }
18:51:15.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.818132  3257 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606 [sys.catalog]: This master's current role is: FOLLOWER
18:51:15.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.822260  3254 catalog_manager.cc:1347] Generated new cluster ID: 4cead99c1d1c4806b78f532c00f0f791
18:51:15.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.822364  3254 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
18:51:15.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.858736  3254 catalog_manager.cc:1370] Generated new certificate authority record
18:51:15.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.859910  3254 catalog_manager.cc:1504] Loading token signing keys...
18:51:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.868923  3254 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 4bf303845aae459e8c299e56ae635eff: Generated new TSK 0
18:51:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.869570  3254 catalog_manager.cc:1514] Initializing in-progress tserver states...
18:51:15.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:15.875931  2944 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/wal
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/logs
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data/info.pb
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:51:15.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.1:0
18:51:15.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.2.224.1
18:51:15.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.1
18:51:15.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:15.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:15.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:15.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:15.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:15.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
18:51:16.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.028555  3280 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
18:51:16.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.028936  3280 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:51:16.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.028977  3280 flags.cc:425] Enabled unsafe flag: --never_fsync=true
18:51:16.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.031085  3280 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
18:51:16.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.031201  3280 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.2.224.1
18:51:16.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.033631  3280 tablet_server_runner.cc:78] Tablet server non-default flags:
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/wal
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.1:0
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data/info.pb
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.1
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.3280
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:51:16.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.2.224.1
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/logs
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 17:59:43 UTC on ccycloud.quasar-xuakos.root.comops.site
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581133
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.033974  3280 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:51:16.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.034363  3280 file_cache.cc:493] Constructed file cache file cache with capacity 419430
18:51:16.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.042583  3285 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
18:51:16.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.042577  3289 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
18:51:16.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.042577  3287 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
18:51:16.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.044215  3280 server_base.cc:1034] running on GCE node
18:51:16.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.044500  3280 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:51:16.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.044817  3280 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:51:16.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.045992  3280 hybrid_clock.cc:648] HybridClock initialized: now 1786474276045967 us; error 41 us; skew 500 ppm
18:51:16.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.047950  3280 webserver.cc:460] Webserver started at http://127.2.224.1:34797/ using document root <none> and password file <none>
18:51:16.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.048293  3280 fs_manager.cc:359] Metadata directory not provided
18:51:16.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.048370  3280 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:51:16.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.048545  3280 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
18:51:16.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.050038  3280 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data/instance:
18:51:16.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "db82a81388da4899ae9000d89c036d45"
18:51:16.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.050513  3280 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/wal/instance:
18:51:16.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "db82a81388da4899ae9000d89c036d45"
18:51:16.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.052376  3280 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
18:51:16.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.053787  3296 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:51:16.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.054103  3280 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.054425  3280 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data,/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/wal
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "db82a81388da4899ae9000d89c036d45"
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.054683  3280 fs_report.cc:383] FS layout report
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/wal
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/wal
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data/data
18:51:16.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:51:16.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:51:16.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.075795  3280 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
18:51:16.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.076248  3280 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:51:16.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.076429  3280 kserver.cc:163] Server-wide thread pool size limit: 3276
18:51:16.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.076778  3280 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
18:51:16.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.077795  3280 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
18:51:16.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.077895  3280 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
18:51:16.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.077939  3280 ts_tablet_manager.cc:610] Registered 0 tablets
18:51:16.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.077960  3280 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
18:51:16.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.086347  3280 rpc_server.cc:307] RPC server started. Bound to: 127.2.224.1:42523
18:51:16.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.086421  3425 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.2.224.1:42523 every 8 connection(s)
18:51:16.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.086928  3280 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/data/info.pb
18:51:16.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.095041  3428 heartbeater.cc:347] Connected to a master server at 127.2.224.61:45759
18:51:16.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.095083  3426 heartbeater.cc:347] Connected to a master server at 127.2.224.60:38703
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.095186  3428 heartbeater.cc:464] Registering TS with master...
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.095187  3426 heartbeater.cc:464] Registering TS with master...
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.095443  3428 heartbeater.cc:511] Master 127.2.224.61:45759 requested a full tablet report, sending...
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.095441  3426 heartbeater.cc:511] Master 127.2.224.60:38703 requested a full tablet report, sending...
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.096199  3083 ts_manager.cc:194] Registered new tserver with Master: db82a81388da4899ae9000d89c036d45 (127.2.224.1:42523)
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.096208  3170 ts_manager.cc:194] Registered new tserver with Master: db82a81388da4899ae9000d89c036d45 (127.2.224.1:42523)
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.096545  3427 heartbeater.cc:347] Connected to a master server at 127.2.224.62:35467
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.096606  3427 heartbeater.cc:464] Registering TS with master...
18:51:16.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.096689  3427 heartbeater.cc:511] Master 127.2.224.62:35467 requested a full tablet report, sending...
18:51:16.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.097061  2944 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskOA3nPh/build/release/bin/kudu as pid 3280
18:51:16.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.097132  2944 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-0/wal/instance
18:51:16.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.097085  2996 ts_manager.cc:194] Registered new tserver with Master: db82a81388da4899ae9000d89c036d45 (127.2.224.1:42523)
18:51:16.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.098012  2996 master_service.cc:496] Signed X509 certificate for tserver {username='slave'} at 127.2.224.1:42041
18:51:16.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.098436  2944 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:16.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:16.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/wal
18:51:16.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/logs
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data/info.pb
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.2:0
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.2.224.2
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.2
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:16.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
18:51:16.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.197425  3434 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
18:51:16.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.197738  3434 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:51:16.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.197767  3434 flags.cc:425] Enabled unsafe flag: --never_fsync=true
18:51:16.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.199178  3434 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
18:51:16.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.199252  3434 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.2.224.2
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.200881  3434 tablet_server_runner.cc:78] Tablet server non-default flags:
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/wal
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.2:0
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data/info.pb
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.2
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:51:16.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.3434
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.2.224.2
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/logs
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 17:59:43 UTC on ccycloud.quasar-xuakos.root.comops.site
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581133
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.201159  3434 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:51:16.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.201475  3434 file_cache.cc:493] Constructed file cache file cache with capacity 419430
18:51:16.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.209550  3443 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
18:51:16.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.209442  3440 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
18:51:16.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.209753  3434 server_base.cc:1034] running on GCE node
18:51:16.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.209437  3441 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
18:51:16.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.210256  3434 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:51:16.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.210614  3434 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:51:16.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.211839  3434 hybrid_clock.cc:648] HybridClock initialized: now 1786474276211890 us; error 119 us; skew 500 ppm
18:51:16.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.213732  3434 webserver.cc:460] Webserver started at http://127.2.224.2:37917/ using document root <none> and password file <none>
18:51:16.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.213989  3434 fs_manager.cc:359] Metadata directory not provided
18:51:16.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.214033  3434 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:51:16.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.214143  3434 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
18:51:16.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.215286  3434 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data/instance:
18:51:16.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "92de4a1670dc496fb640e1abfead3dc2"
18:51:16.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.215651  3434 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/wal/instance:
18:51:16.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "92de4a1670dc496fb640e1abfead3dc2"
18:51:16.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.217805  3434 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.004s	sys 0.000s
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.218880  3450 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.219285  3434 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.219425  3434 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data,/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/wal
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "92de4a1670dc496fb640e1abfead3dc2"
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.219491  3434 fs_report.cc:383] FS layout report
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/wal
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/wal
18:51:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data/data
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:51:16.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:51:16.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.236416  3434 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
18:51:16.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.236845  3434 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:51:16.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.236990  3434 kserver.cc:163] Server-wide thread pool size limit: 3276
18:51:16.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.237219  3434 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
18:51:16.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.237843  3434 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
18:51:16.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.237905  3434 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
18:51:16.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.237932  3434 ts_tablet_manager.cc:610] Registered 0 tablets
18:51:16.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.237946  3434 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
18:51:16.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.245363  3434 rpc_server.cc:307] RPC server started. Bound to: 127.2.224.2:43007
18:51:16.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.245584  3576 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.2.224.2:43007 every 8 connection(s)
18:51:16.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.245841  3434 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/data/info.pb
18:51:16.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.254999  3580 heartbeater.cc:347] Connected to a master server at 127.2.224.61:45759
18:51:16.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255108  3579 heartbeater.cc:347] Connected to a master server at 127.2.224.62:35467
18:51:16.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255200  3580 heartbeater.cc:464] Registering TS with master...
18:51:16.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255200  3579 heartbeater.cc:464] Registering TS with master...
18:51:16.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255396  3578 heartbeater.cc:347] Connected to a master server at 127.2.224.60:38703
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255575  3578 heartbeater.cc:464] Registering TS with master...
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255460  3579 heartbeater.cc:511] Master 127.2.224.62:35467 requested a full tablet report, sending...
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255664  3578 heartbeater.cc:511] Master 127.2.224.60:38703 requested a full tablet report, sending...
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255678  2944 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskOA3nPh/build/release/bin/kudu as pid 3434
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255743  2944 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-1/wal/instance
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.255460  3580 heartbeater.cc:511] Master 127.2.224.61:45759 requested a full tablet report, sending...
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.256184  3170 ts_manager.cc:194] Registered new tserver with Master: 92de4a1670dc496fb640e1abfead3dc2 (127.2.224.2:43007)
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.256289  3083 ts_manager.cc:194] Registered new tserver with Master: 92de4a1670dc496fb640e1abfead3dc2 (127.2.224.2:43007)
18:51:16.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.256812  2996 ts_manager.cc:194] Registered new tserver with Master: 92de4a1670dc496fb640e1abfead3dc2 (127.2.224.2:43007)
18:51:16.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.257309  2944 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:16.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskOA3nPh/build/release/bin/kudu
18:51:16.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/wal
18:51:16.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data
18:51:16.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:51:16.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:51:16.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:51:16.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:16.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:16.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:16.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/logs
18:51:16.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data/info.pb
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.3:0
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.2.224.3
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.3
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
18:51:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.257453  2996 master_service.cc:496] Signed X509 certificate for tserver {username='slave'} at 127.2.224.2:57003
18:51:16.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.364928  3586 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
18:51:16.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.365188  3586 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:51:16.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.365216  3586 flags.cc:425] Enabled unsafe flag: --never_fsync=true
18:51:16.367 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.366739  3586 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
18:51:16.367 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.366822  3586 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.2.224.3
18:51:16.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.368525  3586 tablet_server_runner.cc:78] Tablet server non-default flags:
18:51:16.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:51:16.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.2.224.20:45261
18:51:16.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:51:16.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/wal
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.2.224.3:0
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data/info.pb
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.2.224.3
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.2.224.62:35467,127.2.224.61:45759,127.2.224.60:38703
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.3586
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.2.224.3
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/logs
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 17:59:43 UTC on ccycloud.quasar-xuakos.root.comops.site
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581133
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.368801  3586 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:51:16.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.369123  3586 file_cache.cc:493] Constructed file cache file cache with capacity 419430
18:51:16.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.375458  3592 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
18:51:16.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.376086  3594 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
18:51:16.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 18:51:16.376155  3591 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
18:51:16.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.376624  3586 server_base.cc:1034] running on GCE node
18:51:16.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.376965  3586 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:51:16.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.377240  3586 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:51:16.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.378553  3586 hybrid_clock.cc:648] HybridClock initialized: now 1786474276378530 us; error 43 us; skew 500 ppm
18:51:16.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.381474  3586 webserver.cc:460] Webserver started at http://127.2.224.3:38081/ using document root <none> and password file <none>
18:51:16.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.382293  3586 fs_manager.cc:359] Metadata directory not provided
18:51:16.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.382401  3586 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:51:16.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.382609  3586 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
18:51:16.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.384040  3586 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data/instance:
18:51:16.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d"
18:51:16.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.384581  3586 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/wal/instance:
18:51:16.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d"
18:51:16.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.387099  3586 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.000s	sys 0.005s
18:51:16.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.388337  3600 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:51:16.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.388727  3586 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.388855  3586 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data,/tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/wal
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d"
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 18:51:16 on dist-test-slave-g350"
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.388932  3586 fs_report.cc:383] FS layout report
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/wal
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/wal
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data/data
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:51:16.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:51:16.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.405371  3586 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
18:51:16.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.405857  3586 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:51:16.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.405972  3586 kserver.cc:163] Server-wide thread pool size limit: 3276
18:51:16.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.406200  3586 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
18:51:16.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.406798  3586 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
18:51:16.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.406839  3586 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
18:51:16.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.406863  3586 ts_tablet_manager.cc:610] Registered 0 tablets
18:51:16.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.406876  3586 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
18:51:16.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.415962  3586 rpc_server.cc:307] RPC server started. Bound to: 127.2.224.3:46689
18:51:16.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.416054  3729 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.2.224.3:46689 every 8 connection(s)
18:51:16.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.416406  3586 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/data/info.pb
18:51:16.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.416808  2944 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskOA3nPh/build/release/bin/kudu as pid 3586
18:51:16.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.416903  2944 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskOA3nPh/test-tmp/mini-kudu-cluster6172038773928686323/ts-2/wal/instance
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.424724  3731 heartbeater.cc:347] Connected to a master server at 127.2.224.62:35467
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.424746  3730 heartbeater.cc:347] Connected to a master server at 127.2.224.60:38703
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.424726  3732 heartbeater.cc:347] Connected to a master server at 127.2.224.61:45759
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.424901  3732 heartbeater.cc:464] Registering TS with master...
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.424902  3730 heartbeater.cc:464] Registering TS with master...
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.425122  3732 heartbeater.cc:511] Master 127.2.224.61:45759 requested a full tablet report, sending...
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.425123  3730 heartbeater.cc:511] Master 127.2.224.60:38703 requested a full tablet report, sending...
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.424902  3731 heartbeater.cc:464] Registering TS with master...
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.425593  3731 heartbeater.cc:511] Master 127.2.224.62:35467 requested a full tablet report, sending...
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.425704  3170 ts_manager.cc:194] Registered new tserver with Master: 6fe0edcea1e34fb08f7f6e47818b8c7d (127.2.224.3:46689)
18:51:16.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.425705  3083 ts_manager.cc:194] Registered new tserver with Master: 6fe0edcea1e34fb08f7f6e47818b8c7d (127.2.224.3:46689)
18:51:16.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.425942  2996 ts_manager.cc:194] Registered new tserver with Master: 6fe0edcea1e34fb08f7f6e47818b8c7d (127.2.224.3:46689)
18:51:16.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.426290  2996 master_service.cc:496] Signed X509 certificate for tserver {username='slave'} at 127.2.224.3:48019
18:51:16.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.434978  2944 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
18:51:16.436 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
18:51:16.438 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

18:51:16.452 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.2.224.62"
      port: 35467
    }
    bound_http_address {
      host: "127.2.224.62"
      port: 45821
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.2.224.61"
      port: 45759
    }
    bound_http_address {
      host: "127.2.224.61"
      port: 44907
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.2.224.60"
      port: 38703
    }
    bound_http_address {
      host: "127.2.224.60"
      port: 37427
    }
  }
}

18:51:16.468 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

18:51:16.472 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.2.224.1"
      port: 42523
    }
    bound_http_address {
      host: "127.2.224.1"
      port: 34797
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.2.224.2"
      port: 43007
    }
    bound_http_address {
      host: "127.2.224.2"
      port: 37917
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.2.224.3"
      port: 46689
    }
    bound_http_address {
      host: "127.2.224.3"
      port: 38081
    }
  }
}

18:51:16.489 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
18:51:16.506 [DEBUG - main] (InternalLoggerFactory.java:63) Using SLF4J as the default logging framework
18:51:16.511 [DEBUG - main] (ResourceLeakDetector.java:129) -Dorg.apache.kudu.shaded.io.netty.leakDetection.level: simple
18:51:16.511 [DEBUG - main] (ResourceLeakDetector.java:130) -Dorg.apache.kudu.shaded.io.netty.leakDetection.targetRecords: 4
18:51:16.518 [DEBUG - main] (ResourceLeakDetectorFactory.java:196) Loaded default ResourceLeakDetector: org.apache.kudu.shaded.io.netty.util.ResourceLeakDetector@68044f4
18:51:16.542 [DEBUG - main] (PlatformDependent0.java:497) -Dio.netty.noUnsafe: false
18:51:16.542 [DEBUG - main] (PlatformDependent0.java:1001) Java version: 8
18:51:16.543 [DEBUG - main] (PlatformDependent0.java:137) sun.misc.Unsafe.theUnsafe: available
18:51:16.544 [DEBUG - main] (PlatformDependent0.java:161) sun.misc.Unsafe.copyMemory: available
18:51:16.544 [DEBUG - main] (PlatformDependent0.java:193) sun.misc.Unsafe.storeFence: available
18:51:16.545 [DEBUG - main] (PlatformDependent0.java:236) java.nio.Buffer.address: available
18:51:16.545 [DEBUG - main] (PlatformDependent0.java:308) direct buffer constructor: available
18:51:16.546 [DEBUG - main] (PlatformDependent0.java:386) java.nio.Bits.unaligned: available, true
18:51:16.546 [DEBUG - main] (PlatformDependent0.java:460) jdk.internal.misc.Unsafe.allocateUninitializedArray(int): unavailable prior to Java9
18:51:16.546 [DEBUG - main] (PlatformDependent0.java:483) java.nio.DirectByteBuffer.<init>(long, {int,long}): available
18:51:16.546 [DEBUG - main] (PlatformDependent.java:1159) sun.misc.Unsafe: available
18:51:16.547 [DEBUG - main] (PlatformDependent.java:1289) -Dio.netty.tmpdir: /tmp (java.io.tmpdir)
18:51:16.547 [DEBUG - main] (PlatformDependent.java:1368) -Dio.netty.bitMode: 64 (sun.arch.data.model)
18:51:16.548 [DEBUG - main] (PlatformDependent.java:178) -Dio.netty.maxDirectMemory: 3799121920 bytes
18:51:16.548 [DEBUG - main] (PlatformDependent.java:185) -Dio.netty.uninitializedArrayAllocationThreshold: -1
18:51:16.550 [DEBUG - main] (CleanerJava6.java:92) java.nio.ByteBuffer.cleaner(): available
18:51:16.550 [DEBUG - main] (PlatformDependent.java:205) -Dio.netty.noPreferDirect: false
18:51:16.561 [DEBUG - main] (PlatformDependent.java:1010) org.jctools-core.MpscChunkedArrayQueue: available
18:51:16.617 [DEBUG - main] (MultithreadEventLoopGroup.java:44) -Dio.netty.eventLoopThreads: 2
18:51:16.632 [DEBUG - main] (GlobalEventExecutor.java:53) -Dio.netty.globalEventExecutor.quietPeriodSeconds: 1
18:51:16.644 [DEBUG - main] (InternalThreadLocalMap.java:100) -Dio.netty.threadLocalMap.stringBuilder.initialSize: 1024
18:51:16.644 [DEBUG - main] (InternalThreadLocalMap.java:101) -Dio.netty.threadLocalMap.stringBuilder.maxSize: 4096
18:51:16.654 [DEBUG - main] (NioEventLoop.java:110) -Dio.netty.noKeySetOptimization: false
18:51:16.654 [DEBUG - main] (NioEventLoop.java:111) -Dio.netty.selectorAutoRebuildThreshold: 512
18:51:16.712 [DEBUG - main] (PooledByteBufAllocator.java:157) -Dio.netty.allocator.numHeapArenas: 2
18:51:16.712 [DEBUG - main] (PooledByteBufAllocator.java:158) -Dio.netty.allocator.numDirectArenas: 2
18:51:16.712 [DEBUG - main] (PooledByteBufAllocator.java:160) -Dio.netty.allocator.pageSize: 8192
18:51:16.712 [DEBUG - main] (PooledByteBufAllocator.java:165) -Dio.netty.allocator.maxOrder: 9
18:51:16.713 [DEBUG - main] (PooledByteBufAllocator.java:169) -Dio.netty.allocator.chunkSize: 4194304
18:51:16.713 [DEBUG - main] (PooledByteBufAllocator.java:170) -Dio.netty.allocator.smallCacheSize: 256
18:51:16.713 [DEBUG - main] (PooledByteBufAllocator.java:171) -Dio.netty.allocator.normalCacheSize: 64
18:51:16.713 [DEBUG - main] (PooledByteBufAllocator.java:172) -Dio.netty.allocator.maxCachedBufferCapacity: 32768
18:51:16.713 [DEBUG - main] (PooledByteBufAllocator.java:173) -Dio.netty.allocator.cacheTrimInterval: 8192
18:51:16.714 [DEBUG - main] (PooledByteBufAllocator.java:174) -Dio.netty.allocator.cacheTrimIntervalMillis: 0
18:51:16.714 [DEBUG - main] (PooledByteBufAllocator.java:175) -Dio.netty.allocator.useCacheForAllThreads: false
18:51:16.714 [DEBUG - main] (PooledByteBufAllocator.java:176) -Dio.netty.allocator.maxCachedByteBuffersPerChunk: 1023
18:51:16.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.742260  3249 catalog_manager.cc:1259] Loaded cluster ID: 4cead99c1d1c4806b78f532c00f0f791
18:51:16.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.742372  3249 catalog_manager.cc:1552] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: loading cluster ID for follower catalog manager: success
18:51:16.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.743916  3249 catalog_manager.cc:1574] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: acquiring CA information for follower catalog manager: success
18:51:16.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.744576  3249 catalog_manager.cc:1602] T 00000000000000000000000000000000 P 166d777cd3eb4aa1a9e212b62a83c917: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
18:51:16.786 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
18:51:16.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.798902  3270 catalog_manager.cc:1259] Loaded cluster ID: 4cead99c1d1c4806b78f532c00f0f791
18:51:16.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.799012  3270 catalog_manager.cc:1552] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: loading cluster ID for follower catalog manager: success
18:51:16.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.800348  3270 catalog_manager.cc:1574] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: acquiring CA information for follower catalog manager: success
18:51:16.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:16.800853  3270 catalog_manager.cc:1602] T 00000000000000000000000000000000 P 8c21386444124f58aa7b00a7bcb81606: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
18:51:17.031 [INFO - main] (Logging.scala:57) Running Spark version 3.1.2
18:51:17.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:17.099422  3427 heartbeater.cc:503] Master 127.2.224.62:35467 was elected leader, sending a full tablet report...
18:51:17.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:17.260104  3579 heartbeater.cc:503] Master 127.2.224.62:35467 was elected leader, sending a full tablet report...
18:51:17.364 [WARN - main] (NativeCodeLoader.java:60) Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
18:51:17.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:17.427112  3731 heartbeater.cc:503] Master 127.2.224.62:35467 was elected leader, sending a full tablet report...
18:51:17.507 [INFO - main] (Logging.scala:57) ==============================================================
18:51:17.508 [INFO - main] (Logging.scala:57) No custom resources configured for spark.driver.
18:51:17.508 [INFO - main] (Logging.scala:57) ==============================================================
18:51:17.509 [INFO - main] (Logging.scala:57) Submitted application: test
18:51:17.551 [INFO - main] (Logging.scala:57) Default ResourceProfile created, executor resources: Map(cores -> name: cores, amount: 1, script: , vendor: , memory -> name: memory, amount: 1024, script: , vendor: , offHeap -> name: offHeap, amount: 0, script: , vendor: ), task resources: Map(cpus -> name: cpus, amount: 1.0)
18:51:17.572 [INFO - main] (Logging.scala:57) Limiting resource is cpu
18:51:17.574 [INFO - main] (Logging.scala:57) Added ResourceProfile id: 0
18:51:17.747 [INFO - main] (Logging.scala:57) Changing view acls to: slave
18:51:17.748 [INFO - main] (Logging.scala:57) Changing modify acls to: slave
18:51:17.749 [INFO - main] (Logging.scala:57) Changing view acls groups to: 
18:51:17.750 [INFO - main] (Logging.scala:57) Changing modify acls groups to: 
18:51:17.750 [INFO - main] (Logging.scala:57) SecurityManager: authentication disabled; ui acls disabled; users  with view permissions: Set(slave); groups with view permissions: Set(); users  with modify permissions: Set(slave); groups with modify permissions: Set()
18:51:18.233 [INFO - main] (Logging.scala:57) Successfully started service 'sparkDriver' on port 45757.
18:51:18.296 [INFO - main] (Logging.scala:57) Registering MapOutputTracker
18:51:18.358 [INFO - main] (Logging.scala:57) Registering BlockManagerMaster
18:51:18.391 [INFO - main] (Logging.scala:57) Using org.apache.spark.storage.DefaultTopologyMapper for getting topology information
18:51:18.393 [INFO - main] (Logging.scala:57) BlockManagerMasterEndpoint up
18:51:18.399 [INFO - main] (Logging.scala:57) Registering BlockManagerMasterHeartbeat
18:51:18.420 [INFO - main] (Logging.scala:57) Created local directory at /tmp/blockmgr-a021c74f-aa26-4874-a6db-29eb408b9037
18:51:18.490 [INFO - main] (Logging.scala:57) MemoryStore started with capacity 1993.9 MiB
18:51:18.521 [INFO - main] (Logging.scala:57) Registering OutputCommitCoordinator
18:51:18.757 [INFO - main] (Logging.scala:57) Starting executor ID driver on host dist-test-slave-g350.c.gcp-upstream.internal
18:51:18.808 [INFO - main] (Logging.scala:57) Successfully started service 'org.apache.spark.network.netty.NettyBlockTransferService' on port 40489.
18:51:18.808 [INFO - main] (NettyBlockTransferService.scala:81) Server created on dist-test-slave-g350.c.gcp-upstream.internal:40489
18:51:18.812 [INFO - main] (Logging.scala:57) Using org.apache.spark.storage.RandomBlockReplicationPolicy for block replication policy
18:51:18.824 [INFO - main] (Logging.scala:57) Registering BlockManager BlockManagerId(driver, dist-test-slave-g350.c.gcp-upstream.internal, 40489, None)
18:51:18.830 [INFO - dispatcher-BlockManagerMaster] (Logging.scala:57) Registering block manager dist-test-slave-g350.c.gcp-upstream.internal:40489 with 1993.9 MiB RAM, BlockManagerId(driver, dist-test-slave-g350.c.gcp-upstream.internal, 40489, None)
18:51:18.835 [INFO - main] (Logging.scala:57) Registered BlockManager BlockManagerId(driver, dist-test-slave-g350.c.gcp-upstream.internal, 40489, None)
18:51:18.839 [INFO - main] (Logging.scala:57) Initialized BlockManager: BlockManagerId(driver, dist-test-slave-g350.c.gcp-upstream.internal, 40489, None)
18:51:19.148 [INFO - main] (Log.java:169) Logging initialized @6316ms to org.sparkproject.jetty.util.log.Slf4jLog
18:51:19.490 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
18:51:19.556 [DEBUG - main] (Connection.java:748) [peer master-127.2.224.62:35467(127.2.224.62:35467)] connecting to peer
18:51:19.566 [DEBUG - main] (DefaultChannelId.java:79) -Dio.netty.processId: 2931 (auto-detected)
18:51:19.571 [DEBUG - main] (NetUtil.java:148) -Djava.net.preferIPv4Stack: true
18:51:19.571 [DEBUG - main] (NetUtil.java:149) -Djava.net.preferIPv6Addresses: false
18:51:19.574 [DEBUG - main] (NetUtilInitializations.java:145) Loopback interface: lo (lo, 127.0.0.1)
18:51:19.575 [DEBUG - main] (NetUtil.java:188) /proc/sys/net/core/somaxconn: 4096
18:51:19.577 [DEBUG - main] (DefaultChannelId.java:101) -Dio.netty.machineId: 02:42:c9:ff:fe:6d:ed:7f (auto-detected)
18:51:19.606 [DEBUG - main] (ByteBufUtil.java:88) -Dio.netty.allocator.type: pooled
18:51:19.607 [DEBUG - main] (ByteBufUtil.java:97) -Dio.netty.threadLocalDirectBufferSize: 0
18:51:19.607 [DEBUG - main] (ByteBufUtil.java:100) -Dio.netty.maxThreadLocalCharBufferSize: 16384
18:51:19.620 [DEBUG - main] (Connection.java:748) [peer master-127.2.224.60:38703(127.2.224.60:38703)] connecting to peer
18:51:19.622 [DEBUG - main] (Connection.java:748) [peer master-127.2.224.61:45759(127.2.224.61:45759)] connecting to peer
18:51:19.658 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.2.224.60:38703(127.2.224.60:38703)] Successfully connected to peer
18:51:19.658 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.2.224.62:35467(127.2.224.62:35467)] Successfully connected to peer
18:51:19.673 [DEBUG - kudu-nio-1] (AbstractByteBuf.java:63) -Dorg.apache.kudu.shaded.io.netty.buffer.checkAccessible: true
18:51:19.673 [DEBUG - kudu-nio-1] (AbstractByteBuf.java:64) -Dorg.apache.kudu.shaded.io.netty.buffer.checkBounds: true
18:51:19.673 [DEBUG - kudu-nio-1] (ResourceLeakDetectorFactory.java:196) Loaded default ResourceLeakDetector: org.apache.kudu.shaded.io.netty.util.ResourceLeakDetector@3e91d6f9
18:51:19.680 [DEBUG - kudu-nio-1] (Recycler.java:96) -Dio.netty.recycler.maxCapacityPerThread: 4096
18:51:19.680 [DEBUG - kudu-nio-1] (Recycler.java:97) -Dio.netty.recycler.ratio: 8
18:51:19.680 [DEBUG - kudu-nio-1] (Recycler.java:98) -Dio.netty.recycler.chunkSize: 32
18:51:19.681 [DEBUG - kudu-nio-1] (Recycler.java:99) -Dio.netty.recycler.blocking: false
18:51:19.681 [DEBUG - kudu-nio-1] (Recycler.java:100) -Dio.netty.recycler.batchFastThreadLocalOnly: true
18:51:19.736 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.2.224.61:45759(127.2.224.61:45759)] Successfully connected to peer
18:51:19.759 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.2.224.60
18:51:19.759 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.2.224.62
18:51:19.890 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.2.224.61
18:51:20.010 [DEBUG - kudu-nio-1] (SslHandler.java:1842) [id: 0xembedded, L:embedded - R:embedded] HANDSHAKEN: protocol:TLSv1.2 cipher suite:TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
18:51:20.010 [DEBUG - kudu-nio-0] (SslHandler.java:1842) [id: 0xembedded, L:embedded - R:embedded] HANDSHAKEN: protocol:TLSv1.2 cipher suite:TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
18:51:20.037 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x98e4646b, L:/127.0.0.1:35216 - R:/127.2.224.62:35467] using SASL/PLAIN
18:51:20.030 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x34c15b72, L:/127.0.0.1:56804 - R:/127.2.224.60:38703] using SASL/PLAIN
18:51:20.050 [DEBUG - kudu-nio-0] (SslHandler.java:1842) [id: 0xembedded, L:embedded - R:embedded] HANDSHAKEN: protocol:TLSv1.2 cipher suite:TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
18:51:20.118 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
18:51:20.127 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x87178e05, L:/127.0.0.1:60492 - R:/127.2.224.61:45759] using SASL/PLAIN
18:51:20.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.208258  2996 catalog_manager.cc:1908] Servicing CreateTable request from {username='slave'} at 127.0.0.1:35216:
18:51:20.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "test"
18:51:20.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
18:51:20.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c1_i"
18:51:20.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c2_s"
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c3_double"
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: DOUBLE
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c4_long"
18:51:20.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT64
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c5_bool"
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c6_short"
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT16
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c7_float"
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: FLOAT
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c8_binary"
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BINARY
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c9_unixtime_micros"
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: UNIXTIME_MICROS
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c10_byte"
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT8
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c11_decimal32"
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: DECIMAL32
18:51:20.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type_attributes {
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       precision: 9
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c12_decimal64"
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: DECIMAL64
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type_attributes {
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       precision: 18
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c13_decimal128"
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: DECIMAL128
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type_attributes {
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       precision: 38
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c14_varchar"
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: VARCHAR
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type_attributes {
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       length: 65535
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "c15_date"
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: DATE
18:51:20.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) num_replicas: 1
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) split_rows_range_bounds {
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   rows: "<redacted>""\006\000\000\000\000\007\001\000\000\0002\000\000\000\006\001\000\000\0002\000\000\000\007\000\000\000\000"
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
18:51:20.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) owner: "testuser"
18:51:20.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.223753  3656 tablet_service.cc:1467] Processing CreateTablet for tablet 46743a4707ff4ddda476b14b959849c6 (DEFAULT_TABLE table=test [id=fa7a523a56624926b7fa07d6ce682f00]), partition=RANGE (key) PARTITION 50 <= VALUES
18:51:20.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.223899  3348 tablet_service.cc:1467] Processing CreateTablet for tablet 3869b0f2136542638b790e74068a3ead (DEFAULT_TABLE table=test [id=fa7a523a56624926b7fa07d6ce682f00]), partition=RANGE (key) PARTITION VALUES < 50
18:51:20.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.224287  3656 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 46743a4707ff4ddda476b14b959849c6. 1 dirs total, 0 dirs full, 0 dirs failed
18:51:20.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.224486  3348 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 3869b0f2136542638b790e74068a3ead. 1 dirs total, 0 dirs full, 0 dirs failed
18:51:20.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.228665  3804 tablet_bootstrap.cc:492] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d: Bootstrap starting.
18:51:20.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.230047  3805 tablet_bootstrap.cc:492] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45: Bootstrap starting.
18:51:20.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.230382  3804 tablet_bootstrap.cc:654] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d: Neither blocks nor log segments found. Creating new log.
18:51:20.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.230847  3804 log.cc:824] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d: Log is configured to *not* fsync() on all Append() calls
18:51:20.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.231294  3805 tablet_bootstrap.cc:654] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45: Neither blocks nor log segments found. Creating new log.
18:51:20.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.232043  3805 log.cc:824] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45: Log is configured to *not* fsync() on all Append() calls
18:51:20.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.232067  3804 tablet_bootstrap.cc:492] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d: No bootstrap required, opened a new log
18:51:20.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.232146  3804 ts_tablet_manager.cc:1397] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d: Time spent bootstrapping tablet: real 0.004s	user 0.003s	sys 0.000s
18:51:20.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.233042  3805 tablet_bootstrap.cc:492] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45: No bootstrap required, opened a new log
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.233115  3805 ts_tablet_manager.cc:1397] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.235692  3804 raft_consensus.cc:348] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d" member_type: VOTER last_known_addr { host: "127.2.224.3" port: 46689 } }
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.235926  3804 raft_consensus.cc:374] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.235968  3804 raft_consensus.cc:729] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 6fe0edcea1e34fb08f7f6e47818b8c7d, State: Initialized, Role: FOLLOWER
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.236158  3804 consensus_queue.cc:260] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [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: true peers { permanent_uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d" member_type: VOTER last_known_addr { host: "127.2.224.3" port: 46689 } }
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.236289  3804 raft_consensus.cc:388] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.236342  3804 raft_consensus.cc:482] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.236230  3805 raft_consensus.cc:348] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "db82a81388da4899ae9000d89c036d45" member_type: VOTER last_known_addr { host: "127.2.224.1" port: 42523 } }
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.236394  3804 raft_consensus.cc:3037] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 0 FOLLOWER]: Advancing to term 1
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.236419  3805 raft_consensus.cc:374] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.236456  3805 raft_consensus.cc:729] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: db82a81388da4899ae9000d89c036d45, State: Initialized, Role: FOLLOWER
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.237140  3804 raft_consensus.cc:504] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d" member_type: VOTER last_known_addr { host: "127.2.224.3" port: 46689 } }
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.237251  3804 leader_election.cc:302] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: 6fe0edcea1e34fb08f7f6e47818b8c7d; no voters: 
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.237450  3804 leader_election.cc:288] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [CANDIDATE]: Term 1 election: Requested vote from peers 
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.237488  3808 raft_consensus.cc:2781] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 1 FOLLOWER]: Leader election won for term 1
18:51:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.237641  3808 raft_consensus.cc:686] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [term 1 LEADER]: Becoming Leader. State: Replica: 6fe0edcea1e34fb08f7f6e47818b8c7d, State: Running, Role: LEADER
18:51:20.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.237742  3808 consensus_queue.cc:237] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d [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: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d" member_type: VOTER last_known_addr { host: "127.2.224.3" port: 46689 } }
18:51:20.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.237780  3804 ts_tablet_manager.cc:1428] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d: Time spent starting tablet: real 0.006s	user 0.006s	sys 0.000s
18:51:20.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.238914  2996 catalog_manager.cc:5495] T 46743a4707ff4ddda476b14b959849c6 P 6fe0edcea1e34fb08f7f6e47818b8c7d reported cstate change: term changed from 0 to 1, leader changed from <none> to 6fe0edcea1e34fb08f7f6e47818b8c7d (127.2.224.3). New cstate: current_term: 1 leader_uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6fe0edcea1e34fb08f7f6e47818b8c7d" member_type: VOTER last_known_addr { host: "127.2.224.3" port: 46689 } health_report { overall_health: HEALTHY } } }
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.239847  3805 consensus_queue.cc:260] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [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: true peers { permanent_uuid: "db82a81388da4899ae9000d89c036d45" member_type: VOTER last_known_addr { host: "127.2.224.1" port: 42523 } }
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.240358  3805 raft_consensus.cc:388] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.240425  3805 raft_consensus.cc:482] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.240532  3805 raft_consensus.cc:3037] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 0 FOLLOWER]: Advancing to term 1
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.241881  3805 raft_consensus.cc:504] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "db82a81388da4899ae9000d89c036d45" member_type: VOTER last_known_addr { host: "127.2.224.1" port: 42523 } }
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.242116  3805 leader_election.cc:302] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: db82a81388da4899ae9000d89c036d45; no voters: 
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.242369  3805 leader_election.cc:288] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [CANDIDATE]: Term 1 election: Requested vote from peers 
18:51:20.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.242777  3805 ts_tablet_manager.cc:1428] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45: Time spent starting tablet: real 0.010s	user 0.004s	sys 0.002s
18:51:20.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.244825  3816 raft_consensus.cc:2781] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 1 FOLLOWER]: Leader election won for term 1
18:51:20.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.245059  3816 raft_consensus.cc:686] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [term 1 LEADER]: Becoming Leader. State: Replica: db82a81388da4899ae9000d89c036d45, State: Running, Role: LEADER
18:51:20.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.245169  3816 consensus_queue.cc:237] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 [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: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "db82a81388da4899ae9000d89c036d45" member_type: VOTER last_known_addr { host: "127.2.224.1" port: 42523 } }
18:51:20.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.246672  2996 catalog_manager.cc:5495] T 3869b0f2136542638b790e74068a3ead P db82a81388da4899ae9000d89c036d45 reported cstate change: term changed from 0 to 1, leader changed from <none> to db82a81388da4899ae9000d89c036d45 (127.2.224.1). New cstate: current_term: 1 leader_uuid: "db82a81388da4899ae9000d89c036d45" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "db82a81388da4899ae9000d89c036d45" member_type: VOTER last_known_addr { host: "127.2.224.1" port: 42523 } health_report { overall_health: HEALTHY } } }
18:51:20.257 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table fa7a523a56624926b7fa07d6ce682f00
18:51:20.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.285928  2996 catalog_manager.cc:1908] Servicing CreateTable request from {username='slave'} at 127.0.0.1:35216:
18:51:20.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "simple-test"
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "val"
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) num_replicas: 1
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:51:20.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
18:51:20.296 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table edfca5211ff54e0790ce150e64f55656
18:51:20.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.295847  3504 tablet_service.cc:1467] Processing CreateTablet for tablet 0823c5c3f13d44ceb686de2a573c26bf (DEFAULT_TABLE table=simple-test [id=edfca5211ff54e0790ce150e64f55656]), partition=RANGE (key) PARTITION UNBOUNDED
18:51:20.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.296391  3504 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 0823c5c3f13d44ceb686de2a573c26bf. 1 dirs total, 0 dirs full, 0 dirs failed
18:51:20.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.299544  3823 tablet_bootstrap.cc:492] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2: Bootstrap starting.
18:51:20.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.300487  3823 tablet_bootstrap.cc:654] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2: Neither blocks nor log segments found. Creating new log.
18:51:20.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.300861  3823 log.cc:824] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2: Log is configured to *not* fsync() on all Append() calls
18:51:20.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.301762  3823 tablet_bootstrap.cc:492] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2: No bootstrap required, opened a new log
18:51:20.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.301851  3823 ts_tablet_manager.cc:1397] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
18:51:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.304565  3823 raft_consensus.cc:348] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "92de4a1670dc496fb640e1abfead3dc2" member_type: VOTER last_known_addr { host: "127.2.224.2" port: 43007 } }
18:51:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.304796  3823 raft_consensus.cc:374] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:51:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.304836  3823 raft_consensus.cc:729] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 92de4a1670dc496fb640e1abfead3dc2, State: Initialized, Role: FOLLOWER
18:51:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.304977  3823 consensus_queue.cc:260] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [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: true peers { permanent_uuid: "92de4a1670dc496fb640e1abfead3dc2" member_type: VOTER last_known_addr { host: "127.2.224.2" port: 43007 } }
18:51:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.305073  3823 raft_consensus.cc:388] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
18:51:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.305109  3823 raft_consensus.cc:482] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
18:51:20.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.305150  3823 raft_consensus.cc:3037] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 0 FOLLOWER]: Advancing to term 1
18:51:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.306033  3823 raft_consensus.cc:504] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "92de4a1670dc496fb640e1abfead3dc2" member_type: VOTER last_known_addr { host: "127.2.224.2" port: 43007 } }
18:51:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.306187  3823 leader_election.cc:302] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: 92de4a1670dc496fb640e1abfead3dc2; no voters: 
18:51:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.306375  3823 leader_election.cc:288] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [CANDIDATE]: Term 1 election: Requested vote from peers 
18:51:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.306478  3825 raft_consensus.cc:2781] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 1 FOLLOWER]: Leader election won for term 1
18:51:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.306627  3823 ts_tablet_manager.cc:1428] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2: Time spent starting tablet: real 0.005s	user 0.005s	sys 0.000s
18:51:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.306692  3825 raft_consensus.cc:686] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [term 1 LEADER]: Becoming Leader. State: Replica: 92de4a1670dc496fb640e1abfead3dc2, State: Running, Role: LEADER
18:51:20.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.306805  3825 consensus_queue.cc:237] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 [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: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "92de4a1670dc496fb640e1abfead3dc2" member_type: VOTER last_known_addr { host: "127.2.224.2" port: 43007 } }
18:51:20.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:20.308244  2996 catalog_manager.cc:5495] T 0823c5c3f13d44ceb686de2a573c26bf P 92de4a1670dc496fb640e1abfead3dc2 reported cstate change: term changed from 0 to 1, leader changed from <none> to 92de4a1670dc496fb640e1abfead3dc2 (127.2.224.2). New cstate: current_term: 1 leader_uuid: "92de4a1670dc496fb640e1abfead3dc2" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "92de4a1670dc496fb640e1abfead3dc2" member_type: VOTER last_known_addr { host: "127.2.224.2" port: 43007 } health_report { overall_health: HEALTHY } } }
18:51:22.861 [INFO - main] (Logging.scala:57) Setting hive.metastore.warehouse.dir ('null') to the value of spark.sql.warehouse.dir ('file:/tmp/dist-test-taskOA3nPh/java/build/dist-test/spark-warehouse').
18:51:22.862 [INFO - main] (Logging.scala:57) Warehouse path is 'file:/tmp/dist-test-taskOA3nPh/java/build/dist-test/spark-warehouse'.
18:51:25.371 [INFO - main] (Logging.scala:57) Registered StateStoreCoordinator endpoint
log4j:WARN No appenders could be found for logger (org.apache.htrace.core.Tracer).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
18:51:25.556 [INFO - main] (Logging.scala:57) Checkpoint root /tmp/spark_kudu1897179910556225034 resolved to file:/tmp/spark_kudu1897179910556225034.
18:51:25.698 [INFO - main] (Logging.scala:57) Writing atomically to file:/tmp/spark_kudu1897179910556225034/metadata using temp file file:/tmp/spark_kudu1897179910556225034/.metadata.1c28a53e-13a0-48cc-90e5-c6e416c54fe1.tmp
18:51:25.931 [INFO - main] (Logging.scala:57) Renamed temp file file:/tmp/spark_kudu1897179910556225034/.metadata.1c28a53e-13a0-48cc-90e5-c6e416c54fe1.tmp to file:/tmp/spark_kudu1897179910556225034/metadata
18:51:25.997 [INFO - main] (Logging.scala:57) Starting [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]. Use file:/tmp/spark_kudu1897179910556225034 to store the query checkpoint.
18:51:26.026 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Reading table [org.apache.spark.sql.execution.streaming.MemoryStreamTable@6d5f7fd9] from DataSourceV2 named 'memory' [org.apache.spark.sql.execution.streaming.MemoryStreamTableProvider$@7c5e9213]
18:51:26.053 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Starting new streaming query.
18:51:26.061 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Stream started from {}
18:51:26.175 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Streaming query made progress: {
  "id" : "4a252ed4-f321-4bf8-b160-110e751dcea6",
  "runId" : "41cc5faa-097e-474c-9769-00b8d799ae7a",
  "name" : null,
  "timestamp" : "2026-08-11T18:51:26.048Z",
  "batchId" : 0,
  "numInputRows" : 0,
  "inputRowsPerSecond" : 0.0,
  "processedRowsPerSecond" : 0.0,
  "durationMs" : {
    "latestOffset" : 0,
    "triggerExecution" : 31
  },
  "stateOperators" : [ ],
  "sources" : [ {
    "description" : "MemoryStream[value#1]",
    "startOffset" : null,
    "endOffset" : null,
    "numInputRows" : 0,
    "inputRowsPerSecond" : 0.0,
    "processedRowsPerSecond" : 0.0
  } ],
  "sink" : {
    "description" : "org.apache.kudu.spark.kudu.KuduSink@8775c03",
    "numOutputRows" : -1
  }
}
18:51:26.613 [INFO - main] (Logging.scala:57) Code generated in 388.820547 ms
18:51:26.654 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Writing atomically to file:/tmp/spark_kudu1897179910556225034/offsets/0 using temp file file:/tmp/spark_kudu1897179910556225034/offsets/.0.99790991-da2e-4e55-a48e-f172f1794ed4.tmp
18:51:26.725 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Renamed temp file file:/tmp/spark_kudu1897179910556225034/offsets/.0.99790991-da2e-4e55-a48e-f172f1794ed4.tmp to file:/tmp/spark_kudu1897179910556225034/offsets/0
18:51:26.726 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Committed offsets for batch 0. Metadata OffsetSeqMetadata(0,1786474286627,Map(spark.sql.streaming.stateStore.providerClass -> org.apache.spark.sql.execution.streaming.state.HDFSBackedStateStoreProvider, spark.sql.streaming.join.stateFormatVersion -> 2, spark.sql.streaming.stateStore.compression.codec -> lz4, spark.sql.streaming.flatMapGroupsWithState.stateFormatVersion -> 2, spark.sql.streaming.multipleWatermarkPolicy -> min, spark.sql.streaming.aggregation.stateFormatVersion -> 2, spark.sql.shuffle.partitions -> 200))
18:51:28.356 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Code generated in 74.843578 ms
18:51:28.718 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Starting job: start at StreamingTest.scala:54
18:51:28.751 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Got job 0 (start at StreamingTest.scala:54) with 1 output partitions
18:51:28.751 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Final stage: ResultStage 0 (start at StreamingTest.scala:54)
18:51:28.752 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Parents of final stage: List()
18:51:28.755 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Missing parents: List()
18:51:28.763 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Submitting ResultStage 0 (MapPartitionsRDD[4] at start at StreamingTest.scala:54), which has no missing parents
18:51:28.912 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Block broadcast_0 stored as values in memory (estimated size 17.1 KiB, free 1993.9 MiB)
18:51:28.965 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Block broadcast_0_piece0 stored as bytes in memory (estimated size 7.8 KiB, free 1993.9 MiB)
18:51:28.970 [INFO - dispatcher-BlockManagerMaster] (Logging.scala:57) Added broadcast_0_piece0 in memory on dist-test-slave-g350.c.gcp-upstream.internal:40489 (size: 7.8 KiB, free: 1993.9 MiB)
18:51:28.974 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Created broadcast 0 from broadcast at DAGScheduler.scala:1388
18:51:28.995 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Submitting 1 missing tasks from ResultStage 0 (MapPartitionsRDD[4] at start at StreamingTest.scala:54) (first 15 tasks are for partitions Vector(0))
18:51:28.997 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Adding task set 0.0 with 1 tasks resource profile 0
18:51:29.098 [INFO - dispatcher-event-loop-1] (Logging.scala:57) Starting task 0.0 in stage 0.0 (TID 0) (dist-test-slave-g350.c.gcp-upstream.internal, executor driver, partition 0, PROCESS_LOCAL, 4803 bytes) taskResourceAssignments Map()
18:51:29.127 [INFO - Executor task launch worker for task 0.0 in stage 0.0 (TID 0)] (Logging.scala:57) Running task 0.0 in stage 0.0 (TID 0)
18:51:29.726 [DEBUG - Executor task launch worker for task 0.0 in stage 0.0 (TID 0)] (SecurityContext.java:320) Importing authentication credentials with one authn token, with no JWT, 1 cert(s), and realUser=slave
18:51:29.731 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table edfca5211ff54e0790ce150e64f55656
18:51:29.741 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table edfca5211ff54e0790ce150e64f55656
18:51:29.752 [INFO - Executor task launch worker for task 0.0 in stage 0.0 (TID 0)] (KuduContext.scala:515) applying operations of type 'upsert' to table 'simple-test'
18:51:29.776 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet 0823c5c3f13d44ceb686de2a573c26bf for table 'simple-test' with partition [<start>, <end>)
18:51:29.782 [DEBUG - Executor task launch worker for task 0.0 in stage 0.0 (TID 0)] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=3, operationSize=114, flusherTask=org.apache.kudu.client.AsyncKuduSession$FlusherTask@7e786c13, flushNotification=Deferred@823658687(state=PENDING, result=null, callback=<none>, errback=<none>)}
18:51:29.777 [DEBUG - kudu-nio-0] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299984, tablet=0823c5c3f13d44ceb686de2a573c26bf@[92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)[L]]}]
18:51:29.801 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet 0823c5c3f13d44ceb686de2a573c26bf for table 'simple-test' with partition [<start>, <end>)
18:51:29.802 [DEBUG - kudu-nio-0] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299999, tablet=0823c5c3f13d44ceb686de2a573c26bf@[92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)[L]]}]
18:51:29.804 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet 0823c5c3f13d44ceb686de2a573c26bf for table 'simple-test' with partition [<start>, <end>)
18:51:29.805 [DEBUG - kudu-nio-0] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299999, tablet=0823c5c3f13d44ceb686de2a573c26bf@[92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)[L]]}]
18:51:29.822 [DEBUG - kudu-nio-0] (Connection.java:748) [peer 92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)] connecting to peer
18:51:29.826 [DEBUG - kudu-nio-1] (Connection.java:757) [peer 92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)] Successfully connected to peer
18:51:29.874 [DEBUG - kudu-nio-1] (SslHandler.java:1842) [id: 0xembedded, L:embedded - R:embedded] HANDSHAKEN: protocol:TLSv1.2 cipher suite:TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
18:51:29.880 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x39cc003f, L:/127.0.0.1:43246 - R:/127.2.224.2:43007] using TOKEN/null
18:51:29.924 [INFO - Executor task launch worker for task 0.0 in stage 0.0 (TID 0)] (KuduContext.scala:536) applied 3 upserts to table 'simple-test' in 164ms
18:51:29.964 [INFO - Executor task launch worker for task 0.0 in stage 0.0 (TID 0)] (Logging.scala:57) Finished task 0.0 in stage 0.0 (TID 0). 6753 bytes result sent to driver
18:51:29.985 [INFO - task-result-getter-0] (Logging.scala:57) Finished task 0.0 in stage 0.0 (TID 0) in 904 ms on dist-test-slave-g350.c.gcp-upstream.internal (executor driver) (1/1)
18:51:29.988 [INFO - task-result-getter-0] (Logging.scala:57) Removed TaskSet 0.0, whose tasks have all completed, from pool 
18:51:30.027 [INFO - dag-scheduler-event-loop] (Logging.scala:57) ResultStage 0 (start at StreamingTest.scala:54) finished in 1.240 s
18:51:30.038 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Job 0 is finished. Cancelling potential speculative or zombie tasks for this job
18:51:30.039 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Killing all running tasks in stage 0: Stage finished
18:51:30.043 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Job 0 finished: start at StreamingTest.scala:54, took 1.324144 s
18:51:30.049 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (KuduContext.scala:445) completed upsert ops: duration histogram: 164ms
18:51:30.079 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Writing atomically to file:/tmp/spark_kudu1897179910556225034/commits/0 using temp file file:/tmp/spark_kudu1897179910556225034/commits/.0.e969da0c-3be4-49e1-ba46-a5861d4433e7.tmp
18:51:30.122 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Renamed temp file file:/tmp/spark_kudu1897179910556225034/commits/.0.e969da0c-3be4-49e1-ba46-a5861d4433e7.tmp to file:/tmp/spark_kudu1897179910556225034/commits/0
18:51:30.142 [INFO - stream execution thread for [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a]] (Logging.scala:57) Streaming query made progress: {
  "id" : "4a252ed4-f321-4bf8-b160-110e751dcea6",
  "runId" : "41cc5faa-097e-474c-9769-00b8d799ae7a",
  "name" : null,
  "timestamp" : "2026-08-11T18:51:26.625Z",
  "batchId" : 0,
  "numInputRows" : 3,
  "inputRowsPerSecond" : 272.72727272727275,
  "processedRowsPerSecond" : 0.8573878250928837,
  "durationMs" : {
    "addBatch" : 2374,
    "getBatch" : 5,
    "latestOffset" : 0,
    "queryPlanning" : 937,
    "triggerExecution" : 3498,
    "walCommit" : 98
  },
  "stateOperators" : [ ],
  "sources" : [ {
    "description" : "MemoryStream[value#1]",
    "startOffset" : null,
    "endOffset" : 0,
    "numInputRows" : 3,
    "inputRowsPerSecond" : 272.72727272727275,
    "processedRowsPerSecond" : 0.8573878250928837
  } ],
  "sink" : {
    "description" : "org.apache.kudu.spark.kudu.KuduSink@8775c03",
    "numOutputRows" : -1
  }
}
18:51:30.168 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table edfca5211ff54e0790ce150e64f55656
18:51:30.342 [INFO - main] (Logging.scala:57) Code generated in 21.153768 ms
18:51:30.505 [INFO - main] (Logging.scala:57) Starting job: collect at StreamingTest.scala:60
18:51:30.507 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Got job 1 (collect at StreamingTest.scala:60) with 1 output partitions
18:51:30.507 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Final stage: ResultStage 1 (collect at StreamingTest.scala:60)
18:51:30.507 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Parents of final stage: List()
18:51:30.508 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Missing parents: List()
18:51:30.511 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Submitting ResultStage 1 (MapPartitionsRDD[11] at map at StreamingTest.scala:60), which has no missing parents
18:51:30.522 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Block broadcast_1 stored as values in memory (estimated size 18.6 KiB, free 1993.8 MiB)
18:51:30.526 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Block broadcast_1_piece0 stored as bytes in memory (estimated size 9.1 KiB, free 1993.8 MiB)
18:51:30.527 [INFO - dispatcher-BlockManagerMaster] (Logging.scala:57) Added broadcast_1_piece0 in memory on dist-test-slave-g350.c.gcp-upstream.internal:40489 (size: 9.1 KiB, free: 1993.9 MiB)
18:51:30.528 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Created broadcast 1 from broadcast at DAGScheduler.scala:1388
18:51:30.529 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Submitting 1 missing tasks from ResultStage 1 (MapPartitionsRDD[11] at map at StreamingTest.scala:60) (first 15 tasks are for partitions Vector(0))
18:51:30.529 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Adding task set 1.0 with 1 tasks resource profile 0
18:51:30.538 [INFO - dispatcher-event-loop-0] (Logging.scala:57) Starting task 0.0 in stage 1.0 (TID 1) (dist-test-slave-g350.c.gcp-upstream.internal, executor driver, partition 0, ANY, 4794 bytes) taskResourceAssignments Map()
18:51:30.540 [INFO - Executor task launch worker for task 0.0 in stage 1.0 (TID 1)] (Logging.scala:57) Running task 0.0 in stage 1.0 (TID 1)
18:51:30.676 [DEBUG - Executor task launch worker for task 0.0 in stage 1.0 (TID 1)] (SecurityContext.java:320) Importing authentication credentials with one authn token, with no JWT, 1 cert(s), and realUser=slave
18:51:30.740 [INFO - Executor task launch worker for task 0.0 in stage 1.0 (TID 1)] (Logging.scala:57) Code generated in 18.738377 ms
18:51:30.811 [DEBUG - kudu-nio-1] (AsyncKuduScanner.java:1374) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7317398695043375104) for scanner KuduScanner(table=simple-test, tablet=0823c5c3f13d44ceb686de2a573c26bf, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
18:51:30.932 [INFO - Executor task launch worker for task 0.0 in stage 1.0 (TID 1)] (Logging.scala:57) Code generated in 87.981519 ms
18:51:30.938 [INFO - Executor task launch worker for task 0.0 in stage 1.0 (TID 1)] (Logging.scala:57) Finished task 0.0 in stage 1.0 (TID 1). 5413 bytes result sent to driver
18:51:30.954 [INFO - task-result-getter-1] (Logging.scala:57) Finished task 0.0 in stage 1.0 (TID 1) in 418 ms on dist-test-slave-g350.c.gcp-upstream.internal (executor driver) (1/1)
18:51:30.955 [INFO - task-result-getter-1] (Logging.scala:57) Removed TaskSet 1.0, whose tasks have all completed, from pool 
18:51:30.956 [INFO - dag-scheduler-event-loop] (Logging.scala:57) ResultStage 1 (collect at StreamingTest.scala:60) finished in 0.440 s
18:51:30.957 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Job 1 is finished. Cancelling potential speculative or zombie tasks for this job
18:51:30.957 [INFO - dag-scheduler-event-loop] (Logging.scala:57) Killing all running tasks in stage 1: Stage finished
18:51:30.958 [INFO - main] (Logging.scala:57) Job 1 finished: collect at StreamingTest.scala:60, took 0.452268 s
18:51:30.963 [INFO - main] (Logging.scala:57) Asked to cancel job group 41cc5faa-097e-474c-9769-00b8d799ae7a
18:51:30.990 [INFO - main] (Logging.scala:57) Asked to cancel job group 41cc5faa-097e-474c-9769-00b8d799ae7a
18:51:30.991 [INFO - main] (Logging.scala:57) Query [id = 4a252ed4-f321-4bf8-b160-110e751dcea6, runId = 41cc5faa-097e-474c-9769-00b8d799ae7a] was stopped
18:51:31.029 [INFO - dispatcher-event-loop-0] (Logging.scala:57) MapOutputTrackerMasterEndpoint stopped!
18:51:31.051 [INFO - main] (Logging.scala:57) MemoryStore cleared
18:51:31.052 [INFO - main] (Logging.scala:57) BlockManager stopped
18:51:31.072 [INFO - main] (Logging.scala:57) BlockManagerMaster stopped
18:51:31.078 [INFO - dispatcher-event-loop-0] (Logging.scala:57) OutputCommitCoordinator stopped!
18:51:31.087 [INFO - main] (Logging.scala:57) Successfully stopped SparkContext
18:51:31.092 [DEBUG - main] (Connection.java:575) [peer 92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)] disconnecting while in state READY
18:51:31.093 [DEBUG - main] (Connection.java:575) [peer master-127.2.224.60:38703(127.2.224.60:38703)] disconnecting while in state READY
18:51:31.093 [DEBUG - main] (Connection.java:575) [peer master-127.2.224.62:35467(127.2.224.62:35467)] disconnecting while in state READY
18:51:31.094 [DEBUG - main] (Connection.java:575) [peer master-127.2.224.61:45759(127.2.224.61:45759)] disconnecting while in state READY
18:51:31.104 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2704) Releasing all remaining resources
18:51:31.105 [DEBUG - kudu-nio-1] (Connection.java:239) [peer 92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)] handling channelInactive
18:51:31.106 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.2.224.62:35467(127.2.224.62:35467)] handling channelInactive
18:51:31.107 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.2.224.62:35467(127.2.224.62:35467)] cleaning up while in state READY due to: connection closed
18:51:31.107 [DEBUG - kudu-nio-1] (Connection.java:709) [peer 92de4a1670dc496fb640e1abfead3dc2(127.2.224.2:43007)] cleaning up while in state READY due to: connection closed
18:51:31.108 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.2.224.60:38703(127.2.224.60:38703)] handling channelInactive
18:51:31.108 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.2.224.60:38703(127.2.224.60:38703)] cleaning up while in state READY due to: connection closed
18:51:31.109 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.2.224.61:45759(127.2.224.61:45759)] handling channelInactive
18:51:31.109 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.2.224.61:45759(127.2.224.61:45759)] cleaning up while in state READY due to: connection closed
18:51:31.112 [DEBUG - kudu-nio-1] (PoolThreadCache.java:221) Freed 22 thread-local buffer(s) from thread: kudu-nio-1
18:51:31.113 [DEBUG - kudu-nio-0] (PoolThreadCache.java:221) Freed 31 thread-local buffer(s) from thread: kudu-nio-0
18:51:31.122 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
18:51:31.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:31.124017  2944 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskOA3nPh/build/release/bin/kudu with pid 3280
18:51:31.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:31.142589  2944 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskOA3nPh/build/release/bin/kudu with pid 3434
18:51:31.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:31.158157  2944 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskOA3nPh/build/release/bin/kudu with pid 3586
18:51:31.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:31.174069  2944 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskOA3nPh/build/release/bin/kudu with pid 2957
18:51:31.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:31.187453  2944 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskOA3nPh/build/release/bin/kudu with pid 3046
18:51:31.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 18:51:31.198097  2944 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskOA3nPh/build/release/bin/kudu with pid 3128
18:51:31.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T18:51:31Z chronyd exiting

Time: 18.343

OK (1 test)

18:51:31.608 [INFO - shutdown-hook-0] (Logging.scala:57) Shutdown hook called
18:51:31.610 [INFO - shutdown-hook-0] (Logging.scala:57) Deleting directory /tmp/spark-c6583d24-6c2c-43eb-9a00-7f7ef71810e5
