[==========] Running 3 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 3 tests from VarcharItest
[ RUN      ] VarcharItest.TestVarcharTruncation
2026-08-12T06:37:38Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T06:37:38Z Disabled control of system clock
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:37:38.398512 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
master
run
--ipki_ca_key_size=768
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.27.6.254:33923
--webserver_interface=127.27.6.254
--webserver_port=0
--builtin_ntp_servers=127.27.6.212:45183
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.27.6.254:33923 with env {}
W20260812 06:37:38.508308 27694 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:38.508580 27694 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:38.508621 27694 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:38.511976 27694 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:38.512048 27694 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:38.512070 27694 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:38.512091 27694 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:38.516144 27694 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:45183
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.27.6.254:33923
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.27.6.254:33923
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.27.6.254
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.27694
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:38.517299 27694 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:38.518476 27694 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:38.524293 27700 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
W20260812 06:37:38.524277 27704 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
W20260812 06:37:38.524288 27701 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
I20260812 06:37:38.524662 27694 server_base.cc:1061] running on GCE node
I20260812 06:37:38.525166 27694 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:38.526154 27694 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:38.527395 27694 hybrid_clock.cc:648] HybridClock initialized: now 1786516658527372 us; error 104 us; skew 500 ppm
I20260812 06:37:38.529526 27694 webserver.cc:533] Webserver started at http://127.27.6.254:38241/ using document root <none> and password file <none>
I20260812 06:37:38.530224 27694 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:38.530324 27694 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:38.530586 27694 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:38.532253 27694 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data/instance:
uuid: "69033b99d5774c8da8c76524f748e1be"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.532847 27694 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/wal/instance:
uuid: "69033b99d5774c8da8c76524f748e1be"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.536440 27694 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:37:38.538797 27715 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.539894 27694 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:37:38.540050 27694 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/wal
uuid: "69033b99d5774c8da8c76524f748e1be"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.540194 27694 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:38.566826 27694 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:38.567652 27694 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:38.567883 27694 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:38.575771 27809 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.254:33923 every 8 connection(s)
I20260812 06:37:38.575780 27694 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.254:33923
I20260812 06:37:38.576967 27694 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:38.579897 27811 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:38.585202 27811 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be: Bootstrap starting.
I20260812 06:37:38.585770 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 27694
I20260812 06:37:38.585960 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/master-0/wal/instance
I20260812 06:37:38.587893 27811 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:38.588771 27811 log.cc:826] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:38.591442 27811 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be: No bootstrap required, opened a new log
I20260812 06:37:38.594691 27811 raft_consensus.cc:359] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "69033b99d5774c8da8c76524f748e1be" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 33923 } }
I20260812 06:37:38.594955 27811 raft_consensus.cc:385] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:38.595095 27811 raft_consensus.cc:740] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 69033b99d5774c8da8c76524f748e1be, State: Initialized, Role: FOLLOWER
I20260812 06:37:38.595589 27811 consensus_queue.cc:260] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [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: "69033b99d5774c8da8c76524f748e1be" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 33923 } }
I20260812 06:37:38.595721 27811 raft_consensus.cc:399] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:38.595830 27811 raft_consensus.cc:493] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:38.595968 27811 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:38.597162 27811 raft_consensus.cc:515] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "69033b99d5774c8da8c76524f748e1be" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 33923 } }
I20260812 06:37:38.597573 27811 leader_election.cc:304] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [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: 69033b99d5774c8da8c76524f748e1be; no voters: 
I20260812 06:37:38.598001 27811 leader_election.cc:290] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:38.598101 27821 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:38.598278 27821 raft_consensus.cc:697] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [term 1 LEADER]: Becoming Leader. State: Replica: 69033b99d5774c8da8c76524f748e1be, State: Running, Role: LEADER
I20260812 06:37:38.598645 27821 consensus_queue.cc:237] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [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: "69033b99d5774c8da8c76524f748e1be" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 33923 } }
I20260812 06:37:38.599310 27811 sys_catalog.cc:565] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:38.600704 27824 sys_catalog.cc:455] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [sys.catalog]: SysCatalogTable state changed. Reason: New leader 69033b99d5774c8da8c76524f748e1be. Latest consensus state: current_term: 1 leader_uuid: "69033b99d5774c8da8c76524f748e1be" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "69033b99d5774c8da8c76524f748e1be" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 33923 } } }
I20260812 06:37:38.600852 27824 sys_catalog.cc:458] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:38.601881 27836 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:38.601127 27822 sys_catalog.cc:455] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "69033b99d5774c8da8c76524f748e1be" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "69033b99d5774c8da8c76524f748e1be" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 33923 } } }
I20260812 06:37:38.602367 27822 sys_catalog.cc:458] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:38.605544 27836 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:38.611335 27836 catalog_manager.cc:1383] Generated new cluster ID: 0152e87faa3d4ec581b35ada5904ae41
I20260812 06:37:38.611430 27836 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:38.617923 27836 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:37:38.618824 27836 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:38.626902 27836 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 69033b99d5774c8da8c76524f748e1be: Generated new TSK 0
I20260812 06:37:38.627568 27836 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:38.631285 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.193:0
--local_ip_for_outbound_sockets=127.27.6.193
--webserver_interface=127.27.6.193
--webserver_port=0
--tserver_master_addrs=127.27.6.254:33923
--builtin_ntp_servers=127.27.6.212:45183
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
W20260812 06:37:38.757999 27848 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:38.758246 27848 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:38.758292 27848 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:38.761873 27848 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:38.762001 27848 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.193
I20260812 06:37:38.766077 27848 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:45183
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.193:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.27.6.193
--webserver_port=0
--tserver_master_addrs=127.27.6.254:33923
--never_fsync=true
--heap_profile_path=/tmp/kudu.27848
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.193

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:38.767225 27848 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:38.768422 27848 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:38.775058 27857 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
W20260812 06:37:38.775146 27865 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
W20260812 06:37:38.775072 27858 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
I20260812 06:37:38.775665 27848 server_base.cc:1061] running on GCE node
I20260812 06:37:38.776077 27848 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:38.776726 27848 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:38.777908 27848 hybrid_clock.cc:648] HybridClock initialized: now 1786516658777887 us; error 37 us; skew 500 ppm
I20260812 06:37:38.779855 27848 webserver.cc:533] Webserver started at http://127.27.6.193:46167/ using document root <none> and password file <none>
I20260812 06:37:38.780552 27848 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:38.780623 27848 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:38.780844 27848 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:38.782423 27848 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data/instance:
uuid: "24760ca17d3c479eb4ff4d3f9920c634"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.782934 27848 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/wal/instance:
uuid: "24760ca17d3c479eb4ff4d3f9920c634"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.786549 27848 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
I20260812 06:37:38.789134 27884 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.790205 27848 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:38.790344 27848 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/wal
uuid: "24760ca17d3c479eb4ff4d3f9920c634"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.790455 27848 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:38.803979 27848 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:38.804811 27848 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:38.805028 27848 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:38.805660 27848 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:38.806757 27848 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:38.806835 27848 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.806910 27848 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:38.806962 27848 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.818818 27848 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.193:41191
I20260812 06:37:38.818881 28069 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.193:41191 every 8 connection(s)
I20260812 06:37:38.820195 27848 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:38.828797 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 27848
I20260812 06:37:38.828945 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-0/wal/instance
I20260812 06:37:38.831662 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.194:0
--local_ip_for_outbound_sockets=127.27.6.194
--webserver_interface=127.27.6.194
--webserver_port=0
--tserver_master_addrs=127.27.6.254:33923
--builtin_ntp_servers=127.27.6.212:45183
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 06:37:38.834307 28070 heartbeater.cc:344] Connected to a master server at 127.27.6.254:33923
I20260812 06:37:38.834616 28070 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:38.835216 28070 heartbeater.cc:507] Master 127.27.6.254:33923 requested a full tablet report, sending...
I20260812 06:37:38.837250 27747 ts_manager.cc:194] Registered new tserver with Master: 24760ca17d3c479eb4ff4d3f9920c634 (127.27.6.193:41191)
I20260812 06:37:38.839107 27747 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.193:42051
W20260812 06:37:38.941967 28079 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:38.942245 28079 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:38.942299 28079 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:38.946254 28079 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:38.946405 28079 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.194
I20260812 06:37:38.950798 28079 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:45183
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.194:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
--webserver_interface=127.27.6.194
--webserver_port=0
--tserver_master_addrs=127.27.6.254:33923
--never_fsync=true
--heap_profile_path=/tmp/kudu.28079
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.194

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:38.952013 28079 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:38.953277 28079 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:38.960273 28085 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
W20260812 06:37:38.960338 28087 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
W20260812 06:37:38.960361 28091 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
I20260812 06:37:38.960649 28079 server_base.cc:1061] running on GCE node
I20260812 06:37:38.961057 28079 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:38.961642 28079 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:38.962831 28079 hybrid_clock.cc:648] HybridClock initialized: now 1786516658962818 us; error 34 us; skew 500 ppm
I20260812 06:37:38.964766 28079 webserver.cc:533] Webserver started at http://127.27.6.194:41903/ using document root <none> and password file <none>
I20260812 06:37:38.965365 28079 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:38.965451 28079 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:38.965683 28079 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:38.967352 28079 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data/instance:
uuid: "3711979c825043f2aadbb25d5afb4bcc"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.967839 28079 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/wal/instance:
uuid: "3711979c825043f2aadbb25d5afb4bcc"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.971315 28079 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.006s	sys 0.000s
I20260812 06:37:38.973604 28104 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.974712 28079 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:38.974834 28079 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/wal
uuid: "3711979c825043f2aadbb25d5afb4bcc"
format_stamp: "Formatted at 2026-08-12 06:37:38 on dist-test-slave-44d2"
I20260812 06:37:38.974941 28079 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:38.986635 28079 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:38.987306 28079 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:38.987511 28079 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:38.988108 28079 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:38.989183 28079 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:38.989234 28079 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.989274 28079 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:38.989288 28079 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.998867 28079 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.194:45009
I20260812 06:37:38.998893 28287 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.194:45009 every 8 connection(s)
I20260812 06:37:38.999908 28079 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
I20260812 06:37:39.008824 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 28079
I20260812 06:37:39.008963 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-1/wal/instance
I20260812 06:37:39.009200 28289 heartbeater.cc:344] Connected to a master server at 127.27.6.254:33923
I20260812 06:37:39.009469 28289 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:39.010092 28289 heartbeater.cc:507] Master 127.27.6.254:33923 requested a full tablet report, sending...
I20260812 06:37:39.011222 27747 ts_manager.cc:194] Registered new tserver with Master: 3711979c825043f2aadbb25d5afb4bcc (127.27.6.194:45009)
I20260812 06:37:39.011508 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.195:0
--local_ip_for_outbound_sockets=127.27.6.195
--webserver_interface=127.27.6.195
--webserver_port=0
--tserver_master_addrs=127.27.6.254:33923
--builtin_ntp_servers=127.27.6.212:45183
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 06:37:39.011961 27747 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.194:48997
W20260812 06:37:39.121444 28294 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:39.121703 28294 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:39.121758 28294 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:39.125567 28294 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:39.125705 28294 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.195
I20260812 06:37:39.130182 28294 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:45183
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.195:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
--webserver_interface=127.27.6.195
--webserver_port=0
--tserver_master_addrs=127.27.6.254:33923
--never_fsync=true
--heap_profile_path=/tmp/kudu.28294
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.195

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:39.131373 28294 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:39.132531 28294 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:39.139369 28304 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
I20260812 06:37:39.139564 28294 server_base.cc:1061] running on GCE node
W20260812 06:37:39.139427 28301 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
W20260812 06:37:39.139384 28308 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
I20260812 06:37:39.140053 28294 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:39.140739 28294 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:39.141924 28294 hybrid_clock.cc:648] HybridClock initialized: now 1786516659141911 us; error 39 us; skew 500 ppm
I20260812 06:37:39.143777 28294 webserver.cc:533] Webserver started at http://127.27.6.195:43955/ using document root <none> and password file <none>
I20260812 06:37:39.144431 28294 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:39.144548 28294 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:39.144788 28294 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:39.146394 28294 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data/instance:
uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168"
format_stamp: "Formatted at 2026-08-12 06:37:39 on dist-test-slave-44d2"
I20260812 06:37:39.146889 28294 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/wal/instance:
uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168"
format_stamp: "Formatted at 2026-08-12 06:37:39 on dist-test-slave-44d2"
I20260812 06:37:39.150291 28294 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.003s	sys 0.002s
I20260812 06:37:39.152534 28323 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:39.153517 28294 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
I20260812 06:37:39.153652 28294 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/wal
uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168"
format_stamp: "Formatted at 2026-08-12 06:37:39 on dist-test-slave-44d2"
I20260812 06:37:39.153755 28294 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:39.183151 28294 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:39.183919 28294 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:39.184149 28294 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:39.184829 28294 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:39.185966 28294 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:39.186049 28294 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:39.186124 28294 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:39.186167 28294 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:39.196460 28294 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.195:44711
I20260812 06:37:39.196509 28526 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.195:44711 every 8 connection(s)
I20260812 06:37:39.197563 28294 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
I20260812 06:37:39.198738 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 28294
I20260812 06:37:39.198853 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharTruncation.1786516658370160-27675-0/minicluster-data/ts-2/wal/instance
I20260812 06:37:39.207903 28528 heartbeater.cc:344] Connected to a master server at 127.27.6.254:33923
I20260812 06:37:39.208153 28528 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:39.208741 28528 heartbeater.cc:507] Master 127.27.6.254:33923 requested a full tablet report, sending...
I20260812 06:37:39.209781 27747 ts_manager.cc:194] Registered new tserver with Master: 5f3a89ccb2cd443e8c4dc7dbdb1bb168 (127.27.6.195:44711)
I20260812 06:37:39.210366 27747 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.195:39897
I20260812 06:37:39.214068 27675 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
I20260812 06:37:39.226773 27747 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:35272:
name: "varchar-table"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "value"
    type: VARCHAR
    is_key: false
    is_nullable: true
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    type_attributes {
      length: 10
    }
    immutable: false
  }
}
num_replicas: 3
split_rows_range_bounds {
}
partition_schema {
  hash_schema {
    columns {
      name: "key"
    }
    num_buckets: 3
    seed: 0
  }
}
W20260812 06:37:39.229689 27747 catalog_manager.cc:7099] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table varchar-table in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
I20260812 06:37:39.250629 27958 tablet_service.cc:1511] Processing CreateTablet for tablet 527013dca386497eb7de81133d7c70c8 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 1, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.250979 28399 tablet_service.cc:1511] Processing CreateTablet for tablet c60bc79f47f0470e86c72ca0d219bd22 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 2, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.251406 28185 tablet_service.cc:1511] Processing CreateTablet for tablet 527013dca386497eb7de81133d7c70c8 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 1, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.251794 27958 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 527013dca386497eb7de81133d7c70c8. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.251804 28399 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c60bc79f47f0470e86c72ca0d219bd22. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.252326 28185 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 527013dca386497eb7de81133d7c70c8. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.251406 28186 tablet_service.cc:1511] Processing CreateTablet for tablet 9c3a68c057864c499dbf05dbf0019e22 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 0, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.252684 28186 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9c3a68c057864c499dbf05dbf0019e22. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.254017 28184 tablet_service.cc:1511] Processing CreateTablet for tablet c60bc79f47f0470e86c72ca0d219bd22 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 2, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.254282 28184 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c60bc79f47f0470e86c72ca0d219bd22. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.256841 28401 tablet_service.cc:1511] Processing CreateTablet for tablet 527013dca386497eb7de81133d7c70c8 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 1, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.257030 27956 tablet_service.cc:1511] Processing CreateTablet for tablet c60bc79f47f0470e86c72ca0d219bd22 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 2, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.257125 28401 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 527013dca386497eb7de81133d7c70c8. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.257275 27956 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c60bc79f47f0470e86c72ca0d219bd22. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.250633 27961 tablet_service.cc:1511] Processing CreateTablet for tablet 9c3a68c057864c499dbf05dbf0019e22 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 0, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.259104 27961 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9c3a68c057864c499dbf05dbf0019e22. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.259217 28402 tablet_service.cc:1511] Processing CreateTablet for tablet 9c3a68c057864c499dbf05dbf0019e22 (DEFAULT_TABLE table=varchar-table [id=453656d6000e4b7997aa4ecbd19d9a77]), partition=HASH (key) PARTITION 0, RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:39.259512 28402 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9c3a68c057864c499dbf05dbf0019e22. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:39.261819 28557 tablet_bootstrap.cc:492] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc: Bootstrap starting.
I20260812 06:37:39.263505 28559 tablet_bootstrap.cc:492] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Bootstrap starting.
I20260812 06:37:39.263353 28558 tablet_bootstrap.cc:492] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634: Bootstrap starting.
I20260812 06:37:39.264294 28557 tablet_bootstrap.cc:654] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.265120 28557 log.cc:826] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:39.265679 28559 tablet_bootstrap.cc:654] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.265679 28558 tablet_bootstrap.cc:654] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.266507 28558 log.cc:826] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:39.266513 28559 log.cc:826] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:39.266844 28557 tablet_bootstrap.cc:492] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc: No bootstrap required, opened a new log
I20260812 06:37:39.267086 28557 ts_tablet_manager.cc:1403] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc: Time spent bootstrapping tablet: real 0.005s	user 0.000s	sys 0.004s
I20260812 06:37:39.268096 28558 tablet_bootstrap.cc:492] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634: No bootstrap required, opened a new log
I20260812 06:37:39.268247 28559 tablet_bootstrap.cc:492] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: No bootstrap required, opened a new log
I20260812 06:37:39.268324 28558 ts_tablet_manager.cc:1403] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634: Time spent bootstrapping tablet: real 0.005s	user 0.003s	sys 0.000s
I20260812 06:37:39.268435 28559 ts_tablet_manager.cc:1403] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Time spent bootstrapping tablet: real 0.005s	user 0.000s	sys 0.004s
I20260812 06:37:39.271147 28557 raft_consensus.cc:359] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.271392 28557 raft_consensus.cc:385] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.271507 28557 raft_consensus.cc:740] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3711979c825043f2aadbb25d5afb4bcc, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.271626 28558 raft_consensus.cc:359] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.271903 28558 raft_consensus.cc:385] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.271988 28558 raft_consensus.cc:740] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 24760ca17d3c479eb4ff4d3f9920c634, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.272001 28557 consensus_queue.cc:260] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.272172 28559 raft_consensus.cc:359] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.272418 28559 raft_consensus.cc:385] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.272526 28559 raft_consensus.cc:740] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5f3a89ccb2cd443e8c4dc7dbdb1bb168, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.272434 28558 consensus_queue.cc:260] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634 [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.272883 28289 heartbeater.cc:499] Master 127.27.6.254:33923 was elected leader, sending a full tablet report...
I20260812 06:37:39.273074 28557 ts_tablet_manager.cc:1434] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc: Time spent starting tablet: real 0.006s	user 0.005s	sys 0.002s
I20260812 06:37:39.273011 28559 consensus_queue.cc:260] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.273358 28557 tablet_bootstrap.cc:492] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc: Bootstrap starting.
I20260812 06:37:39.273406 28558 ts_tablet_manager.cc:1434] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634: Time spent starting tablet: real 0.005s	user 0.005s	sys 0.000s
I20260812 06:37:39.273725 28070 heartbeater.cc:499] Master 127.27.6.254:33923 was elected leader, sending a full tablet report...
I20260812 06:37:39.273828 28559 ts_tablet_manager.cc:1434] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Time spent starting tablet: real 0.005s	user 0.000s	sys 0.005s
I20260812 06:37:39.274197 28559 tablet_bootstrap.cc:492] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Bootstrap starting.
I20260812 06:37:39.274222 28528 heartbeater.cc:499] Master 127.27.6.254:33923 was elected leader, sending a full tablet report...
I20260812 06:37:39.274528 28557 tablet_bootstrap.cc:654] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.275333 28559 tablet_bootstrap.cc:654] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.275331 28558 tablet_bootstrap.cc:492] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634: Bootstrap starting.
I20260812 06:37:39.276417 28558 tablet_bootstrap.cc:654] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.278108 28557 tablet_bootstrap.cc:492] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc: No bootstrap required, opened a new log
I20260812 06:37:39.278236 28558 tablet_bootstrap.cc:492] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634: No bootstrap required, opened a new log
I20260812 06:37:39.278249 28557 ts_tablet_manager.cc:1403] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc: Time spent bootstrapping tablet: real 0.005s	user 0.002s	sys 0.000s
I20260812 06:37:39.278363 28558 ts_tablet_manager.cc:1403] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:37:39.278940 28557 raft_consensus.cc:359] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.279100 28557 raft_consensus.cc:385] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.279021 28558 raft_consensus.cc:359] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.279170 28557 raft_consensus.cc:740] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3711979c825043f2aadbb25d5afb4bcc, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.279210 28558 raft_consensus.cc:385] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.279274 28558 raft_consensus.cc:740] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 24760ca17d3c479eb4ff4d3f9920c634, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.279358 28557 consensus_queue.cc:260] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.279469 28558 consensus_queue.cc:260] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [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: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.279699 28557 ts_tablet_manager.cc:1434] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc: Time spent starting tablet: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:37:39.279806 28558 ts_tablet_manager.cc:1434] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634: Time spent starting tablet: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:37:39.279939 28557 tablet_bootstrap.cc:492] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc: Bootstrap starting.
I20260812 06:37:39.280071 28558 tablet_bootstrap.cc:492] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634: Bootstrap starting.
I20260812 06:37:39.281080 28559 tablet_bootstrap.cc:492] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: No bootstrap required, opened a new log
I20260812 06:37:39.281098 28557 tablet_bootstrap.cc:654] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.281188 28558 tablet_bootstrap.cc:654] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.281301 28559 ts_tablet_manager.cc:1403] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Time spent bootstrapping tablet: real 0.007s	user 0.000s	sys 0.002s
I20260812 06:37:39.281960 28559 raft_consensus.cc:359] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.282193 28559 raft_consensus.cc:385] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.282302 28559 raft_consensus.cc:740] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5f3a89ccb2cd443e8c4dc7dbdb1bb168, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.282402 28558 tablet_bootstrap.cc:492] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634: No bootstrap required, opened a new log
I20260812 06:37:39.282505 28558 ts_tablet_manager.cc:1403] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:39.282631 28559 consensus_queue.cc:260] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [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: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.283022 28558 raft_consensus.cc:359] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.283128 28558 raft_consensus.cc:385] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.283145 28557 tablet_bootstrap.cc:492] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc: No bootstrap required, opened a new log
I20260812 06:37:39.283164 28558 raft_consensus.cc:740] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 24760ca17d3c479eb4ff4d3f9920c634, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.283226 28557 ts_tablet_manager.cc:1403] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:37:39.283304 28558 consensus_queue.cc:260] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634 [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.283584 28558 ts_tablet_manager.cc:1434] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634: Time spent starting tablet: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:37:39.283766 28557 raft_consensus.cc:359] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.283882 28557 raft_consensus.cc:385] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.283926 28557 raft_consensus.cc:740] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3711979c825043f2aadbb25d5afb4bcc, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.283987 28559 ts_tablet_manager.cc:1434] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.001s
I20260812 06:37:39.284052 28557 consensus_queue.cc:260] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [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: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.284184 28559 tablet_bootstrap.cc:492] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Bootstrap starting.
I20260812 06:37:39.284340 28557 ts_tablet_manager.cc:1434] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc: Time spent starting tablet: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:37:39.285343 28559 tablet_bootstrap.cc:654] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:39.286671 28559 tablet_bootstrap.cc:492] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: No bootstrap required, opened a new log
I20260812 06:37:39.286746 28559 ts_tablet_manager.cc:1403] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:37:39.287177 28559 raft_consensus.cc:359] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.287264 28559 raft_consensus.cc:385] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:39.287325 28559 raft_consensus.cc:740] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5f3a89ccb2cd443e8c4dc7dbdb1bb168, State: Initialized, Role: FOLLOWER
I20260812 06:37:39.287482 28559 consensus_queue.cc:260] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.287775 28559 ts_tablet_manager.cc:1434] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Time spent starting tablet: real 0.001s	user 0.000s	sys 0.000s
I20260812 06:37:39.298530 28563 raft_consensus.cc:493] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:39.298740 28563 raft_consensus.cc:515] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.299893 28563 leader_election.cc:290] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 24760ca17d3c479eb4ff4d3f9920c634 (127.27.6.193:41191), 5f3a89ccb2cd443e8c4dc7dbdb1bb168 (127.27.6.195:44711)
I20260812 06:37:39.302768 28566 raft_consensus.cc:493] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:39.303078 28566 raft_consensus.cc:515] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.304421 28566 leader_election.cc:290] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 24760ca17d3c479eb4ff4d3f9920c634 (127.27.6.193:41191), 3711979c825043f2aadbb25d5afb4bcc (127.27.6.194:45009)
I20260812 06:37:39.305307 28433 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c60bc79f47f0470e86c72ca0d219bd22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" is_pre_election: true
I20260812 06:37:39.305521 28433 raft_consensus.cc:2468] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 0.
I20260812 06:37:39.306090 28107 leader_election.cc:304] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [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: 3711979c825043f2aadbb25d5afb4bcc, 5f3a89ccb2cd443e8c4dc7dbdb1bb168; no voters: 
I20260812 06:37:39.306337 27991 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c60bc79f47f0470e86c72ca0d219bd22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "24760ca17d3c479eb4ff4d3f9920c634" is_pre_election: true
I20260812 06:37:39.306424 28563 raft_consensus.cc:2804] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Leader pre-election won for term 1
I20260812 06:37:39.306495 28563 raft_consensus.cc:493] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:39.306540 28563 raft_consensus.cc:3060] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.306638 27991 raft_consensus.cc:2468] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 0.
I20260812 06:37:39.307806 28563 raft_consensus.cc:515] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.308254 28563 leader_election.cc:290] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [CANDIDATE]: Term 1 election: Requested vote from peers 24760ca17d3c479eb4ff4d3f9920c634 (127.27.6.193:41191), 5f3a89ccb2cd443e8c4dc7dbdb1bb168 (127.27.6.195:44711)
I20260812 06:37:39.308733 28433 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c60bc79f47f0470e86c72ca0d219bd22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168"
I20260812 06:37:39.308744 27990 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "527013dca386497eb7de81133d7c70c8" candidate_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "24760ca17d3c479eb4ff4d3f9920c634" is_pre_election: true
I20260812 06:37:39.308729 27991 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "c60bc79f47f0470e86c72ca0d219bd22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "24760ca17d3c479eb4ff4d3f9920c634"
I20260812 06:37:39.308866 28433 raft_consensus.cc:3060] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.308887 27991 raft_consensus.cc:3060] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.308897 27990 raft_consensus.cc:2468] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 5f3a89ccb2cd443e8c4dc7dbdb1bb168 in term 0.
I20260812 06:37:39.309525 28330 leader_election.cc:304] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [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: 24760ca17d3c479eb4ff4d3f9920c634, 5f3a89ccb2cd443e8c4dc7dbdb1bb168; no voters: 
I20260812 06:37:39.309798 28566 raft_consensus.cc:2804] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Leader pre-election won for term 1
I20260812 06:37:39.309851 28566 raft_consensus.cc:493] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:39.309902 28566 raft_consensus.cc:3060] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.310066 28433 raft_consensus.cc:2468] T c60bc79f47f0470e86c72ca0d219bd22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 1.
I20260812 06:37:39.310154 27991 raft_consensus.cc:2468] T c60bc79f47f0470e86c72ca0d219bd22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 1.
I20260812 06:37:39.310395 28107 leader_election.cc:304] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [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: 3711979c825043f2aadbb25d5afb4bcc, 5f3a89ccb2cd443e8c4dc7dbdb1bb168; no voters: 
I20260812 06:37:39.310567 28563 raft_consensus.cc:2804] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:39.310842 28563 raft_consensus.cc:697] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [term 1 LEADER]: Becoming Leader. State: Replica: 3711979c825043f2aadbb25d5afb4bcc, State: Running, Role: LEADER
I20260812 06:37:39.310879 28214 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "527013dca386497eb7de81133d7c70c8" candidate_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3711979c825043f2aadbb25d5afb4bcc" is_pre_election: true
I20260812 06:37:39.310913 28566 raft_consensus.cc:515] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.311043 28214 raft_consensus.cc:2468] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 5f3a89ccb2cd443e8c4dc7dbdb1bb168 in term 0.
I20260812 06:37:39.311175 28563 consensus_queue.cc:237] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.311293 28566 leader_election.cc:290] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [CANDIDATE]: Term 1 election: Requested vote from peers 24760ca17d3c479eb4ff4d3f9920c634 (127.27.6.193:41191), 3711979c825043f2aadbb25d5afb4bcc (127.27.6.194:45009)
I20260812 06:37:39.311616 27991 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "527013dca386497eb7de81133d7c70c8" candidate_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "24760ca17d3c479eb4ff4d3f9920c634"
I20260812 06:37:39.311753 27991 raft_consensus.cc:3060] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.311877 28214 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "527013dca386497eb7de81133d7c70c8" candidate_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3711979c825043f2aadbb25d5afb4bcc"
I20260812 06:37:39.311982 28214 raft_consensus.cc:3060] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.312721 27991 raft_consensus.cc:2468] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 5f3a89ccb2cd443e8c4dc7dbdb1bb168 in term 1.
I20260812 06:37:39.313050 28214 raft_consensus.cc:2468] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 5f3a89ccb2cd443e8c4dc7dbdb1bb168 in term 1.
I20260812 06:37:39.313150 28330 leader_election.cc:304] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [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: 24760ca17d3c479eb4ff4d3f9920c634, 5f3a89ccb2cd443e8c4dc7dbdb1bb168; no voters: 
I20260812 06:37:39.314095 28566 raft_consensus.cc:2804] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:39.314348 28566 raft_consensus.cc:697] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 1 LEADER]: Becoming Leader. State: Replica: 5f3a89ccb2cd443e8c4dc7dbdb1bb168, State: Running, Role: LEADER
I20260812 06:37:39.314674 28566 consensus_queue.cc:237] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [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: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } }
I20260812 06:37:39.315086 27747 catalog_manager.cc:5719] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc reported cstate change: term changed from 0 to 1, leader changed from <none> to 3711979c825043f2aadbb25d5afb4bcc (127.27.6.194). New cstate: current_term: 1 leader_uuid: "3711979c825043f2aadbb25d5afb4bcc" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } health_report { overall_health: UNKNOWN } } }
I20260812 06:37:39.318313 27744 catalog_manager.cc:5719] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 reported cstate change: term changed from 0 to 1, leader changed from <none> to 5f3a89ccb2cd443e8c4dc7dbdb1bb168 (127.27.6.195). New cstate: current_term: 1 leader_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } health_report { overall_health: HEALTHY } } }
W20260812 06:37:39.324610 28073 tablet.cc:2406] T 527013dca386497eb7de81133d7c70c8 P 24760ca17d3c479eb4ff4d3f9920c634: Can't schedule compaction. Clean time has not been advanced past its initial value.
I20260812 06:37:39.412562 28563 raft_consensus.cc:493] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:39.412752 28563 raft_consensus.cc:515] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.413352 28563 leader_election.cc:290] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 5f3a89ccb2cd443e8c4dc7dbdb1bb168 (127.27.6.195:44711), 24760ca17d3c479eb4ff4d3f9920c634 (127.27.6.193:41191)
I20260812 06:37:39.413937 28433 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9c3a68c057864c499dbf05dbf0019e22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" is_pre_election: true
I20260812 06:37:39.413937 27991 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9c3a68c057864c499dbf05dbf0019e22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "24760ca17d3c479eb4ff4d3f9920c634" is_pre_election: true
I20260812 06:37:39.414108 28433 raft_consensus.cc:2468] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 0.
I20260812 06:37:39.414108 27991 raft_consensus.cc:2468] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 0.
I20260812 06:37:39.414448 28107 leader_election.cc:304] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [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: 3711979c825043f2aadbb25d5afb4bcc, 5f3a89ccb2cd443e8c4dc7dbdb1bb168; no voters: 
I20260812 06:37:39.414654 28563 raft_consensus.cc:2804] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Leader pre-election won for term 1
I20260812 06:37:39.414721 28563 raft_consensus.cc:493] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:39.414762 28563 raft_consensus.cc:3060] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.415604 28563 raft_consensus.cc:515] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.416018 28563 leader_election.cc:290] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [CANDIDATE]: Term 1 election: Requested vote from peers 5f3a89ccb2cd443e8c4dc7dbdb1bb168 (127.27.6.195:44711), 24760ca17d3c479eb4ff4d3f9920c634 (127.27.6.193:41191)
I20260812 06:37:39.416409 28433 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9c3a68c057864c499dbf05dbf0019e22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168"
I20260812 06:37:39.416514 27991 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9c3a68c057864c499dbf05dbf0019e22" candidate_uuid: "3711979c825043f2aadbb25d5afb4bcc" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "24760ca17d3c479eb4ff4d3f9920c634"
I20260812 06:37:39.416584 28433 raft_consensus.cc:3060] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.416685 27991 raft_consensus.cc:3060] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:39.417488 28433 raft_consensus.cc:2468] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 1.
I20260812 06:37:39.417655 27991 raft_consensus.cc:2468] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 3711979c825043f2aadbb25d5afb4bcc in term 1.
I20260812 06:37:39.417912 28107 leader_election.cc:304] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [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: 3711979c825043f2aadbb25d5afb4bcc, 5f3a89ccb2cd443e8c4dc7dbdb1bb168; no voters: 
I20260812 06:37:39.418080 28563 raft_consensus.cc:2804] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:39.418174 28563 raft_consensus.cc:697] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [term 1 LEADER]: Becoming Leader. State: Replica: 3711979c825043f2aadbb25d5afb4bcc, State: Running, Role: LEADER
I20260812 06:37:39.418316 28563 consensus_queue.cc:237] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [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: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } }
I20260812 06:37:39.420074 27744 catalog_manager.cc:5719] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc reported cstate change: term changed from 0 to 1, leader changed from <none> to 3711979c825043f2aadbb25d5afb4bcc (127.27.6.194). New cstate: current_term: 1 leader_uuid: "3711979c825043f2aadbb25d5afb4bcc" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 } health_report { overall_health: UNKNOWN } } }
W20260812 06:37:39.448721 28529 tablet.cc:2406] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168: Can't schedule compaction. Clean time has not been advanced past its initial value.
I20260812 06:37:39.449625 28433 raft_consensus.cc:1275] T 9c3a68c057864c499dbf05dbf0019e22 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [term 1 FOLLOWER]: Refusing update from remote peer 3711979c825043f2aadbb25d5afb4bcc: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
I20260812 06:37:39.449678 27991 raft_consensus.cc:1275] T 9c3a68c057864c499dbf05dbf0019e22 P 24760ca17d3c479eb4ff4d3f9920c634 [term 1 FOLLOWER]: Refusing update from remote peer 3711979c825043f2aadbb25d5afb4bcc: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
I20260812 06:37:39.450459 28563 consensus_queue.cc:1048] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [LEADER]: Connected to new peer: Peer: permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:39.450703 28580 consensus_queue.cc:1048] T 9c3a68c057864c499dbf05dbf0019e22 P 3711979c825043f2aadbb25d5afb4bcc [LEADER]: Connected to new peer: Peer: permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:39.456796 28592 mvcc.cc:204] Tried to move back new op lower bound from 7317572237098541056 to 7317572236978589696. Current Snapshot: MvccSnapshot[applied={T|T < 7317572237098541056}]
I20260812 06:37:39.459697 28595 mvcc.cc:204] Tried to move back new op lower bound from 7317572237098541056 to 7317572236978589696. Current Snapshot: MvccSnapshot[applied={T|T < 7317572237098541056}]
W20260812 06:37:39.506865 28290 tablet.cc:2406] T 527013dca386497eb7de81133d7c70c8 P 3711979c825043f2aadbb25d5afb4bcc: Can't schedule compaction. Clean time has not been advanced past its initial value.
I20260812 06:37:39.701844 28566 consensus_queue.cc:1048] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [LEADER]: Connected to new peer: Peer: permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:39.706728 28610 consensus_queue.cc:1048] T 527013dca386497eb7de81133d7c70c8 P 5f3a89ccb2cd443e8c4dc7dbdb1bb168 [LEADER]: Connected to new peer: Peer: permanent_uuid: "3711979c825043f2aadbb25d5afb4bcc" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 45009 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
W20260812 06:37:39.847565 28155 tablet_service.cc:3018] Rejecting scan request for tablet c60bc79f47f0470e86c72ca0d219bd22: Uninitialized: clean time has not yet been initialized
W20260812 06:37:39.849426 28369 tablet_service.cc:3018] Rejecting scan request for tablet c60bc79f47f0470e86c72ca0d219bd22: Uninitialized: clean time has not yet been initialized
I20260812 06:37:39.856119 28580 consensus_queue.cc:1048] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [LEADER]: Connected to new peer: Peer: permanent_uuid: "24760ca17d3c479eb4ff4d3f9920c634" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 41191 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
W20260812 06:37:39.858247 27924 tablet_service.cc:3018] Rejecting scan request for tablet c60bc79f47f0470e86c72ca0d219bd22: Uninitialized: clean time has not yet been initialized
I20260812 06:37:39.862658 28563 consensus_queue.cc:1048] T c60bc79f47f0470e86c72ca0d219bd22 P 3711979c825043f2aadbb25d5afb4bcc [LEADER]: Connected to new peer: Peer: permanent_uuid: "5f3a89ccb2cd443e8c4dc7dbdb1bb168" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 44711 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:40.263049 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 27848
I20260812 06:37:40.281009 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 28079
I20260812 06:37:40.298557 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 28294
I20260812 06:37:40.314816 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 27694
2026-08-12T06:37:40Z chronyd exiting
[       OK ] VarcharItest.TestVarcharTruncation (1964 ms)
[ RUN      ] VarcharItest.TestInvalidLength
2026-08-12T06:37:40Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T06:37:40Z Disabled control of system clock
I20260812 06:37:40.355124 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
master
run
--ipki_ca_key_size=768
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.27.6.254:37811
--webserver_interface=127.27.6.254
--webserver_port=0
--builtin_ntp_servers=127.27.6.212:44113
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.27.6.254:37811 with env {}
W20260812 06:37:40.465426 28631 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:40.465658 28631 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:40.465698 28631 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:40.468953 28631 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:40.469009 28631 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:40.469038 28631 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:40.469055 28631 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:40.472908 28631 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44113
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.27.6.254:37811
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.27.6.254:37811
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.27.6.254
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.28631
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:40.473948 28631 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:40.475095 28631 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:40.480898 28641 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
W20260812 06:37:40.480865 28640 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
W20260812 06:37:40.481101 28643 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
I20260812 06:37:40.481634 28631 server_base.cc:1061] running on GCE node
I20260812 06:37:40.482167 28631 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:40.483215 28631 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:40.484424 28631 hybrid_clock.cc:648] HybridClock initialized: now 1786516660484392 us; error 42 us; skew 500 ppm
I20260812 06:37:40.486564 28631 webserver.cc:533] Webserver started at http://127.27.6.254:37033/ using document root <none> and password file <none>
I20260812 06:37:40.487166 28631 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:40.487226 28631 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:40.487498 28631 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:40.489178 28631 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data/instance:
uuid: "918c9b27e1c344329e76ec4664e0ce90"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.489689 28631 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/wal/instance:
uuid: "918c9b27e1c344329e76ec4664e0ce90"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.493357 28631 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.000s	sys 0.004s
I20260812 06:37:40.495877 28651 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.497066 28631 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
I20260812 06:37:40.497229 28631 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/wal
uuid: "918c9b27e1c344329e76ec4664e0ce90"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.497344 28631 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:40.509618 28631 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:40.510366 28631 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:40.510577 28631 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:40.518296 28734 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.254:37811 every 8 connection(s)
I20260812 06:37:40.518296 28631 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.254:37811
I20260812 06:37:40.519492 28631 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:40.521384 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 28631
I20260812 06:37:40.521535 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/master-0/wal/instance
I20260812 06:37:40.522782 28736 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:40.529557 28736 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90: Bootstrap starting.
I20260812 06:37:40.532040 28736 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:40.532837 28736 log.cc:826] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:40.534509 28736 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90: No bootstrap required, opened a new log
I20260812 06:37:40.536971 28736 raft_consensus.cc:359] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "918c9b27e1c344329e76ec4664e0ce90" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 37811 } }
I20260812 06:37:40.537191 28736 raft_consensus.cc:385] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:40.537277 28736 raft_consensus.cc:740] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 918c9b27e1c344329e76ec4664e0ce90, State: Initialized, Role: FOLLOWER
I20260812 06:37:40.537727 28736 consensus_queue.cc:260] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [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: "918c9b27e1c344329e76ec4664e0ce90" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 37811 } }
I20260812 06:37:40.537874 28736 raft_consensus.cc:399] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:40.537961 28736 raft_consensus.cc:493] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:40.538080 28736 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:40.538861 28736 raft_consensus.cc:515] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "918c9b27e1c344329e76ec4664e0ce90" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 37811 } }
I20260812 06:37:40.539206 28736 leader_election.cc:304] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [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: 918c9b27e1c344329e76ec4664e0ce90; no voters: 
I20260812 06:37:40.539507 28736 leader_election.cc:290] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:40.539657 28745 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:40.539878 28745 raft_consensus.cc:697] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [term 1 LEADER]: Becoming Leader. State: Replica: 918c9b27e1c344329e76ec4664e0ce90, State: Running, Role: LEADER
I20260812 06:37:40.540138 28745 consensus_queue.cc:237] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [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: "918c9b27e1c344329e76ec4664e0ce90" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 37811 } }
I20260812 06:37:40.540716 28736 sys_catalog.cc:565] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:40.541796 28746 sys_catalog.cc:455] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "918c9b27e1c344329e76ec4664e0ce90" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "918c9b27e1c344329e76ec4664e0ce90" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 37811 } } }
I20260812 06:37:40.541949 28746 sys_catalog.cc:458] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:40.542991 28760 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:40.542203 28747 sys_catalog.cc:455] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 918c9b27e1c344329e76ec4664e0ce90. Latest consensus state: current_term: 1 leader_uuid: "918c9b27e1c344329e76ec4664e0ce90" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "918c9b27e1c344329e76ec4664e0ce90" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 37811 } } }
I20260812 06:37:40.543548 28747 sys_catalog.cc:458] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:40.545604 28760 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:40.550920 28760 catalog_manager.cc:1383] Generated new cluster ID: e8e8d075823d4259a50451da401b9fb8
I20260812 06:37:40.551005 28760 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:40.558679 28760 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:37:40.559631 28760 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:40.564868 28760 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 918c9b27e1c344329e76ec4664e0ce90: Generated new TSK 0
I20260812 06:37:40.565523 28760 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:40.574280 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.193:0
--local_ip_for_outbound_sockets=127.27.6.193
--webserver_interface=127.27.6.193
--webserver_port=0
--tserver_master_addrs=127.27.6.254:37811
--builtin_ntp_servers=127.27.6.212:44113
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
W20260812 06:37:40.690881 28784 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:40.691125 28784 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:40.691169 28784 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:40.694748 28784 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:40.694870 28784 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.193
I20260812 06:37:40.699007 28784 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44113
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.193:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.27.6.193
--webserver_port=0
--tserver_master_addrs=127.27.6.254:37811
--never_fsync=true
--heap_profile_path=/tmp/kudu.28784
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.193

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:40.700270 28784 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:40.701642 28784 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:40.708995 28794 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
W20260812 06:37:40.708974 28796 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
W20260812 06:37:40.708974 28791 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
I20260812 06:37:40.709748 28784 server_base.cc:1061] running on GCE node
I20260812 06:37:40.710258 28784 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:40.710981 28784 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:40.712198 28784 hybrid_clock.cc:648] HybridClock initialized: now 1786516660712166 us; error 48 us; skew 500 ppm
I20260812 06:37:40.714491 28784 webserver.cc:533] Webserver started at http://127.27.6.193:44441/ using document root <none> and password file <none>
I20260812 06:37:40.715185 28784 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:40.715248 28784 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:40.715517 28784 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:40.717437 28784 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data/instance:
uuid: "3b937f715f21423588159205aca718a9"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.717996 28784 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/wal/instance:
uuid: "3b937f715f21423588159205aca718a9"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.721935 28784 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.000s
I20260812 06:37:40.724543 28811 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.725809 28784 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:40.725948 28784 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/wal
uuid: "3b937f715f21423588159205aca718a9"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.726042 28784 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:40.747020 28784 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:40.747890 28784 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:40.748155 28784 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:40.748944 28784 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:40.750180 28784 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:40.750241 28784 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.750324 28784 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:40.750375 28784 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.761791 28784 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.193:46863
I20260812 06:37:40.761881 28994 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.193:46863 every 8 connection(s)
I20260812 06:37:40.763037 28784 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:40.772033 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 28784
I20260812 06:37:40.772131 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-0/wal/instance
I20260812 06:37:40.772908 28995 heartbeater.cc:344] Connected to a master server at 127.27.6.254:37811
I20260812 06:37:40.773216 28995 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:40.773762 28995 heartbeater.cc:507] Master 127.27.6.254:37811 requested a full tablet report, sending...
I20260812 06:37:40.774600 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.194:0
--local_ip_for_outbound_sockets=127.27.6.194
--webserver_interface=127.27.6.194
--webserver_port=0
--tserver_master_addrs=127.27.6.254:37811
--builtin_ntp_servers=127.27.6.212:44113
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 06:37:40.775481 28675 ts_manager.cc:194] Registered new tserver with Master: 3b937f715f21423588159205aca718a9 (127.27.6.193:46863)
I20260812 06:37:40.777070 28675 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.193:46119
W20260812 06:37:40.887934 28999 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:40.888192 28999 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:40.888240 28999 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:40.891692 28999 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:40.891808 28999 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.194
I20260812 06:37:40.895666 28999 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44113
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.194:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
--webserver_interface=127.27.6.194
--webserver_port=0
--tserver_master_addrs=127.27.6.254:37811
--never_fsync=true
--heap_profile_path=/tmp/kudu.28999
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.194

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:40.896842 28999 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:40.898077 28999 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:40.905488 29009 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
W20260812 06:37:40.905503 29010 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
I20260812 06:37:40.905658 28999 server_base.cc:1061] running on GCE node
W20260812 06:37:40.905557 29014 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
I20260812 06:37:40.906211 28999 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:40.906837 28999 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:40.908046 28999 hybrid_clock.cc:648] HybridClock initialized: now 1786516660908035 us; error 32 us; skew 500 ppm
I20260812 06:37:40.910012 28999 webserver.cc:533] Webserver started at http://127.27.6.194:42555/ using document root <none> and password file <none>
I20260812 06:37:40.910663 28999 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:40.910763 28999 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:40.911001 28999 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:40.912714 28999 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data/instance:
uuid: "a923d96ed8c24c8391b304ed0d9ddcf9"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.913226 28999 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/wal/instance:
uuid: "a923d96ed8c24c8391b304ed0d9ddcf9"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.916823 28999 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:37:40.919165 29025 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.920212 28999 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:40.920357 28999 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/wal
uuid: "a923d96ed8c24c8391b304ed0d9ddcf9"
format_stamp: "Formatted at 2026-08-12 06:37:40 on dist-test-slave-44d2"
I20260812 06:37:40.920468 28999 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:40.948752 28999 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:40.949541 28999 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:40.949782 28999 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:40.950436 28999 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:40.951568 28999 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:40.951645 28999 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.951717 28999 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:40.951767 28999 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.961434 28999 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.194:33679
I20260812 06:37:40.961489 29212 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.194:33679 every 8 connection(s)
I20260812 06:37:40.962495 28999 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
I20260812 06:37:40.972582 29213 heartbeater.cc:344] Connected to a master server at 127.27.6.254:37811
I20260812 06:37:40.972903 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 28999
I20260812 06:37:40.972972 29213 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:40.973031 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-1/wal/instance
I20260812 06:37:40.973644 29213 heartbeater.cc:507] Master 127.27.6.254:37811 requested a full tablet report, sending...
I20260812 06:37:40.975004 28675 ts_manager.cc:194] Registered new tserver with Master: a923d96ed8c24c8391b304ed0d9ddcf9 (127.27.6.194:33679)
I20260812 06:37:40.975627 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.195:0
--local_ip_for_outbound_sockets=127.27.6.195
--webserver_interface=127.27.6.195
--webserver_port=0
--tserver_master_addrs=127.27.6.254:37811
--builtin_ntp_servers=127.27.6.212:44113
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 06:37:40.975626 28675 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.194:59755
W20260812 06:37:41.086148 29220 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:41.086395 29220 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:41.086445 29220 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:41.090001 29220 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:41.090157 29220 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.195
I20260812 06:37:41.094902 29220 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44113
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.195:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
--webserver_interface=127.27.6.195
--webserver_port=0
--tserver_master_addrs=127.27.6.254:37811
--never_fsync=true
--heap_profile_path=/tmp/kudu.29220
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.195

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:41.096177 29220 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:41.097570 29220 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:41.104955 29228 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
W20260812 06:37:41.104955 29227 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
W20260812 06:37:41.105218 29236 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
I20260812 06:37:41.105882 29220 server_base.cc:1061] running on GCE node
I20260812 06:37:41.106362 29220 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:41.106920 29220 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:41.108124 29220 hybrid_clock.cc:648] HybridClock initialized: now 1786516661108099 us; error 35 us; skew 500 ppm
I20260812 06:37:41.110044 29220 webserver.cc:533] Webserver started at http://127.27.6.195:43419/ using document root <none> and password file <none>
I20260812 06:37:41.110647 29220 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:41.110736 29220 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:41.110980 29220 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:41.112668 29220 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data/instance:
uuid: "d5b1be7a2a044f4e9fe23de0ad789b89"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.113188 29220 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/wal/instance:
uuid: "d5b1be7a2a044f4e9fe23de0ad789b89"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.116680 29220 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.003s	sys 0.001s
I20260812 06:37:41.119150 29243 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.120160 29220 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:41.120301 29220 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/wal
uuid: "d5b1be7a2a044f4e9fe23de0ad789b89"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.120411 29220 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:41.144876 29220 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:41.145628 29220 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:41.145843 29220 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:41.146464 29220 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:41.147504 29220 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:41.147581 29220 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.147652 29220 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:41.147702 29220 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.157531 29220 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.195:41203
I20260812 06:37:41.157562 29436 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.195:41203 every 8 connection(s)
I20260812 06:37:41.158519 29220 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
I20260812 06:37:41.162878 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 29220
I20260812 06:37:41.162967 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestInvalidLength.1786516658370160-27675-0/minicluster-data/ts-2/wal/instance
I20260812 06:37:41.168856 29439 heartbeater.cc:344] Connected to a master server at 127.27.6.254:37811
I20260812 06:37:41.169147 29439 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:41.169678 29439 heartbeater.cc:507] Master 127.27.6.254:37811 requested a full tablet report, sending...
I20260812 06:37:41.170747 28675 ts_manager.cc:194] Registered new tserver with Master: d5b1be7a2a044f4e9fe23de0ad789b89 (127.27.6.195:41203)
I20260812 06:37:41.171316 28675 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.195:43523
I20260812 06:37:41.177513 27675 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
I20260812 06:37:41.184991 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 28784
I20260812 06:37:41.201177 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 28999
I20260812 06:37:41.214087 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 29220
I20260812 06:37:41.226954 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 28631
2026-08-12T06:37:41Z chronyd exiting
[       OK ] VarcharItest.TestInvalidLength (911 ms)
[ RUN      ] VarcharItest.TestVarcharRangePartition
2026-08-12T06:37:41Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T06:37:41Z Disabled control of system clock
I20260812 06:37:41.266003 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
master
run
--ipki_ca_key_size=768
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.27.6.254:44949
--webserver_interface=127.27.6.254
--webserver_port=0
--builtin_ntp_servers=127.27.6.212:44039
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.27.6.254:44949 with env {}
W20260812 06:37:41.374737 29455 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:41.374977 29455 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:41.375020 29455 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:41.378504 29455 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:41.378572 29455 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:41.378594 29455 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:41.378612 29455 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:41.382691 29455 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44039
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.27.6.254:44949
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.27.6.254:44949
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.27.6.254
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.29455
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:41.383814 29455 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:41.385108 29455 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:41.390869 29462 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
W20260812 06:37:41.391077 29468 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
I20260812 06:37:41.391150 29455 server_base.cc:1061] running on GCE node
W20260812 06:37:41.391229 29464 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
I20260812 06:37:41.391778 29455 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:41.392781 29455 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:41.394009 29455 hybrid_clock.cc:648] HybridClock initialized: now 1786516661393982 us; error 40 us; skew 500 ppm
I20260812 06:37:41.396107 29455 webserver.cc:533] Webserver started at http://127.27.6.254:34361/ using document root <none> and password file <none>
I20260812 06:37:41.396880 29455 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:41.396943 29455 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:41.397164 29455 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:41.398818 29455 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data/instance:
uuid: "4150a9f0651f4086b995a98749d670c1"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.399343 29455 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/wal/instance:
uuid: "4150a9f0651f4086b995a98749d670c1"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.403112 29455 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.002s	sys 0.004s
I20260812 06:37:41.405496 29479 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.406648 29455 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:41.406807 29455 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/wal
uuid: "4150a9f0651f4086b995a98749d670c1"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.406988 29455 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:41.427255 29455 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:41.428143 29455 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:41.428387 29455 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:41.436939 29455 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.254:44949
I20260812 06:37:41.436934 29563 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.254:44949 every 8 connection(s)
I20260812 06:37:41.438189 29455 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:41.441337 29565 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:41.441838 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 29455
I20260812 06:37:41.442052 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/master-0/wal/instance
I20260812 06:37:41.447139 29565 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1: Bootstrap starting.
I20260812 06:37:41.449550 29565 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:41.450317 29565 log.cc:826] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:41.452318 29565 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1: No bootstrap required, opened a new log
I20260812 06:37:41.454845 29565 raft_consensus.cc:359] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4150a9f0651f4086b995a98749d670c1" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 44949 } }
I20260812 06:37:41.455032 29565 raft_consensus.cc:385] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:41.455101 29565 raft_consensus.cc:740] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 4150a9f0651f4086b995a98749d670c1, State: Initialized, Role: FOLLOWER
I20260812 06:37:41.455543 29565 consensus_queue.cc:260] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [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: "4150a9f0651f4086b995a98749d670c1" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 44949 } }
I20260812 06:37:41.455689 29565 raft_consensus.cc:399] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:41.455762 29565 raft_consensus.cc:493] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:41.455881 29565 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:41.456821 29565 raft_consensus.cc:515] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4150a9f0651f4086b995a98749d670c1" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 44949 } }
I20260812 06:37:41.457157 29565 leader_election.cc:304] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [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: 4150a9f0651f4086b995a98749d670c1; no voters: 
I20260812 06:37:41.457453 29565 leader_election.cc:290] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:41.457654 29573 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:41.457909 29573 raft_consensus.cc:697] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [term 1 LEADER]: Becoming Leader. State: Replica: 4150a9f0651f4086b995a98749d670c1, State: Running, Role: LEADER
I20260812 06:37:41.458243 29573 consensus_queue.cc:237] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [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: "4150a9f0651f4086b995a98749d670c1" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 44949 } }
I20260812 06:37:41.458701 29565 sys_catalog.cc:565] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:41.460179 29574 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "4150a9f0651f4086b995a98749d670c1" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4150a9f0651f4086b995a98749d670c1" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 44949 } } }
I20260812 06:37:41.460182 29575 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4150a9f0651f4086b995a98749d670c1. Latest consensus state: current_term: 1 leader_uuid: "4150a9f0651f4086b995a98749d670c1" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4150a9f0651f4086b995a98749d670c1" member_type: VOTER last_known_addr { host: "127.27.6.254" port: 44949 } } }
I20260812 06:37:41.460333 29574 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:41.460386 29575 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:41.461553 29584 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:41.464298 29584 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:41.469753 29584 catalog_manager.cc:1383] Generated new cluster ID: 3b9affaea3094638bdb13a75c856577e
I20260812 06:37:41.469832 29584 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:41.494726 29584 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:37:41.495951 29584 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:41.534963 29584 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 4150a9f0651f4086b995a98749d670c1: Generated new TSK 0
I20260812 06:37:41.535776 29584 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:41.552001 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.193:0
--local_ip_for_outbound_sockets=127.27.6.193
--webserver_interface=127.27.6.193
--webserver_port=0
--tserver_master_addrs=127.27.6.254:44949
--builtin_ntp_servers=127.27.6.212:44039
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
W20260812 06:37:41.665261 29598 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:41.665566 29598 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:41.665647 29598 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:41.669132 29598 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:41.669324 29598 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.193
I20260812 06:37:41.673341 29598 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44039
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.193:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.27.6.193
--webserver_port=0
--tserver_master_addrs=127.27.6.254:44949
--never_fsync=true
--heap_profile_path=/tmp/kudu.29598
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.193

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:41.674585 29598 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:41.675793 29598 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:41.682704 29610 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
W20260812 06:37:41.682719 29612 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
I20260812 06:37:41.682780 29598 server_base.cc:1061] running on GCE node
W20260812 06:37:41.682708 29609 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
I20260812 06:37:41.683487 29598 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:41.684092 29598 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:41.685266 29598 hybrid_clock.cc:648] HybridClock initialized: now 1786516661685249 us; error 41 us; skew 500 ppm
I20260812 06:37:41.687086 29598 webserver.cc:533] Webserver started at http://127.27.6.193:40783/ using document root <none> and password file <none>
I20260812 06:37:41.687676 29598 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:41.687734 29598 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:41.687984 29598 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:41.689620 29598 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data/instance:
uuid: "1bdd71d8ef3e4be29f4fd593a470e963"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.690133 29598 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/wal/instance:
uuid: "1bdd71d8ef3e4be29f4fd593a470e963"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.693506 29598 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:37:41.695648 29623 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.696697 29598 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:41.696820 29598 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/wal
uuid: "1bdd71d8ef3e4be29f4fd593a470e963"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.696918 29598 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:41.726660 29598 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:41.727461 29598 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:41.727694 29598 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:41.728363 29598 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:41.729496 29598 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:41.729549 29598 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.729590 29598 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:41.729605 29598 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.739761 29598 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.193:34199
I20260812 06:37:41.739792 29807 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.193:34199 every 8 connection(s)
I20260812 06:37:41.740840 29598 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:41.750422 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 29598
I20260812 06:37:41.750560 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-0/wal/instance
I20260812 06:37:41.750665 29808 heartbeater.cc:344] Connected to a master server at 127.27.6.254:44949
I20260812 06:37:41.750937 29808 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:41.751529 29808 heartbeater.cc:507] Master 127.27.6.254:44949 requested a full tablet report, sending...
I20260812 06:37:41.753003 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.194:0
--local_ip_for_outbound_sockets=127.27.6.194
--webserver_interface=127.27.6.194
--webserver_port=0
--tserver_master_addrs=127.27.6.254:44949
--builtin_ntp_servers=127.27.6.212:44039
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 06:37:41.753113 29505 ts_manager.cc:194] Registered new tserver with Master: 1bdd71d8ef3e4be29f4fd593a470e963 (127.27.6.193:34199)
I20260812 06:37:41.754288 29505 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.193:46239
W20260812 06:37:41.860838 29817 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:41.861068 29817 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:41.861110 29817 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:41.864468 29817 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:41.864625 29817 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.194
I20260812 06:37:41.868508 29817 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44039
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.194:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
--webserver_interface=127.27.6.194
--webserver_port=0
--tserver_master_addrs=127.27.6.254:44949
--never_fsync=true
--heap_profile_path=/tmp/kudu.29817
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.194

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:41.869704 29817 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:41.870945 29817 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:41.877460 29824 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
W20260812 06:37:41.877478 29822 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
W20260812 06:37:41.877478 29828 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
I20260812 06:37:41.877852 29817 server_base.cc:1061] running on GCE node
I20260812 06:37:41.878275 29817 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:41.878865 29817 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:41.880023 29817 hybrid_clock.cc:648] HybridClock initialized: now 1786516661879992 us; error 40 us; skew 500 ppm
I20260812 06:37:41.881862 29817 webserver.cc:533] Webserver started at http://127.27.6.194:38563/ using document root <none> and password file <none>
I20260812 06:37:41.882449 29817 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:41.882506 29817 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:41.882752 29817 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:41.884285 29817 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data/instance:
uuid: "e41fb6dd727e43db845c9e3b50a3c7bd"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.884795 29817 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/wal/instance:
uuid: "e41fb6dd727e43db845c9e3b50a3c7bd"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.888101 29817 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:37:41.890240 29837 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.891427 29817 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.000s	sys 0.001s
I20260812 06:37:41.891534 29817 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/wal
uuid: "e41fb6dd727e43db845c9e3b50a3c7bd"
format_stamp: "Formatted at 2026-08-12 06:37:41 on dist-test-slave-44d2"
I20260812 06:37:41.891618 29817 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:41.915104 29817 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:41.915824 29817 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:41.915989 29817 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:41.916617 29817 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:41.917723 29817 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:41.917809 29817 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.917882 29817 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:41.917934 29817 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:41.927938 29817 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.194:43351
I20260812 06:37:41.927973 30029 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.194:43351 every 8 connection(s)
I20260812 06:37:41.929078 29817 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/data/info.pb
I20260812 06:37:41.929888 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 29817
I20260812 06:37:41.929982 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-1/wal/instance
I20260812 06:37:41.932945 27675 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskHUK93v/build/debug/bin/kudu
/tmp/dist-test-taskHUK93v/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/wal
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data
--block_manager=log
--webserver_interface=localhost
--never_fsync
--enable_minidumps=false
--redact=none
--metrics_log_interval_ms=1000
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/logs
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
--server_dump_info_format=pb
--rpc_server_allow_ephemeral_ports
--unlock_experimental_flags
--unlock_unsafe_flags
--logtostderr
--logbuflevel=-1
--ipki_server_key_size=768
--openssl_security_level_override=0
tserver
run
--rpc_bind_addresses=127.27.6.195:0
--local_ip_for_outbound_sockets=127.27.6.195
--webserver_interface=127.27.6.195
--webserver_port=0
--tserver_master_addrs=127.27.6.254:44949
--builtin_ntp_servers=127.27.6.212:44039
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 06:37:41.941279 30030 heartbeater.cc:344] Connected to a master server at 127.27.6.254:44949
I20260812 06:37:41.941617 30030 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:41.942250 30030 heartbeater.cc:507] Master 127.27.6.254:44949 requested a full tablet report, sending...
I20260812 06:37:41.943529 29505 ts_manager.cc:194] Registered new tserver with Master: e41fb6dd727e43db845c9e3b50a3c7bd (127.27.6.194:43351)
I20260812 06:37:41.944260 29505 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.194:59715
W20260812 06:37:42.049546 30036 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:42.049880 30036 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:42.050001 30036 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:42.053994 30036 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:42.054207 30036 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.6.195
I20260812 06:37:42.058688 30036 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.27.6.212:44039
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data
--fs_wal_dir=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.27.6.195:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
--webserver_interface=127.27.6.195
--webserver_port=0
--tserver_master_addrs=127.27.6.254:44949
--never_fsync=true
--heap_profile_path=/tmp/kudu.30036
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.27.6.195

Tablet server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:42.059993 30036 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:42.061453 30036 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:42.068763 30042 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
W20260812 06:37:42.068754 30048 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
W20260812 06:37:42.068765 30043 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
I20260812 06:37:42.069082 30036 server_base.cc:1061] running on GCE node
I20260812 06:37:42.069553 30036 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:42.070210 30036 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:42.071439 30036 hybrid_clock.cc:648] HybridClock initialized: now 1786516662071417 us; error 29 us; skew 500 ppm
I20260812 06:37:42.073604 30036 webserver.cc:533] Webserver started at http://127.27.6.195:39139/ using document root <none> and password file <none>
I20260812 06:37:42.074280 30036 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:42.074348 30036 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:42.074615 30036 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:42.076296 30036 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data/instance:
uuid: "a22be6d0763f4b309ae42eb003d92d3e"
format_stamp: "Formatted at 2026-08-12 06:37:42 on dist-test-slave-44d2"
I20260812 06:37:42.076920 30036 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/wal/instance:
uuid: "a22be6d0763f4b309ae42eb003d92d3e"
format_stamp: "Formatted at 2026-08-12 06:37:42 on dist-test-slave-44d2"
I20260812 06:37:42.080461 30036 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:37:42.082805 30061 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:42.083880 30036 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:42.083981 30036 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data,/tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/wal
uuid: "a22be6d0763f4b309ae42eb003d92d3e"
format_stamp: "Formatted at 2026-08-12 06:37:42 on dist-test-slave-44d2"
I20260812 06:37:42.084134 30036 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/wal
metadata directory: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/wal
1 data directories: /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:42.123082 30036 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:42.123839 30036 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:42.124064 30036 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:42.124737 30036 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:42.125818 30036 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:42.125867 30036 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:42.125941 30036 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:42.125983 30036 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:42.136025 30036 rpc_server.cc:307] RPC server started. Bound to: 127.27.6.195:34841
I20260812 06:37:42.136049 30234 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.27.6.195:34841 every 8 connection(s)
I20260812 06:37:42.137174 30036 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/data/info.pb
I20260812 06:37:42.141806 27675 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskHUK93v/build/debug/bin/kudu as pid 30036
I20260812 06:37:42.141965 27675 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskHUK93v/test-tmp/varchar-itest.0.VarcharItest.TestVarcharRangePartition.1786516658370160-27675-0/minicluster-data/ts-2/wal/instance
I20260812 06:37:42.146636 30237 heartbeater.cc:344] Connected to a master server at 127.27.6.254:44949
I20260812 06:37:42.146901 30237 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:42.147418 30237 heartbeater.cc:507] Master 127.27.6.254:44949 requested a full tablet report, sending...
I20260812 06:37:42.148711 29505 ts_manager.cc:194] Registered new tserver with Master: a22be6d0763f4b309ae42eb003d92d3e (127.27.6.195:34841)
I20260812 06:37:42.149324 29505 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.27.6.195:37497
I20260812 06:37:42.156602 27675 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
I20260812 06:37:42.165789 29505 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:48950:
name: "varchar-table"
schema {
  columns {
    name: "key"
    type: VARCHAR
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    type_attributes {
      length: 10
    }
    immutable: false
  }
  columns {
    name: "value"
    type: VARCHAR
    is_key: false
    is_nullable: true
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    type_attributes {
      length: 10
    }
    immutable: false
  }
}
num_replicas: 3
split_rows_range_bounds {
  rows: "<redacted>""\006\001\000\000\000\000\000\000\000\000\000\003\000\000\000\000\000\000\000\007\001\000\003\000\000\000\000\000\000\000\003\000\000\000\000\000\000\000"
  indirect_data: "<redacted>""barfoo"
}
partition_schema {
  range_schema {
    columns {
      name: "key"
    }
  }
}
W20260812 06:37:42.168561 29505 catalog_manager.cc:7099] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table varchar-table in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
I20260812 06:37:42.185541 30134 tablet_service.cc:1511] Processing CreateTablet for tablet 783e4920045849a387ef0b09f62b28f2 (DEFAULT_TABLE table=varchar-table [id=19acca1115f64f929857110bf9b0cc15]), partition=RANGE (key) PARTITION "bar" <= VALUES < "foo"
I20260812 06:37:42.185542 29917 tablet_service.cc:1511] Processing CreateTablet for tablet 783e4920045849a387ef0b09f62b28f2 (DEFAULT_TABLE table=varchar-table [id=19acca1115f64f929857110bf9b0cc15]), partition=RANGE (key) PARTITION "bar" <= VALUES < "foo"
I20260812 06:37:42.185834 29706 tablet_service.cc:1511] Processing CreateTablet for tablet 783e4920045849a387ef0b09f62b28f2 (DEFAULT_TABLE table=varchar-table [id=19acca1115f64f929857110bf9b0cc15]), partition=RANGE (key) PARTITION "bar" <= VALUES < "foo"
I20260812 06:37:42.186906 29706 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 783e4920045849a387ef0b09f62b28f2. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:42.186913 29917 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 783e4920045849a387ef0b09f62b28f2. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:42.186911 30134 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 783e4920045849a387ef0b09f62b28f2. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:42.192173 30265 tablet_bootstrap.cc:492] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963: Bootstrap starting.
I20260812 06:37:42.193145 30270 tablet_bootstrap.cc:492] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd: Bootstrap starting.
I20260812 06:37:42.194938 30265 tablet_bootstrap.cc:654] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:42.194942 30270 tablet_bootstrap.cc:654] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:42.195683 30265 log.cc:826] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:42.195739 30270 log.cc:826] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:42.196557 30271 tablet_bootstrap.cc:492] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e: Bootstrap starting.
I20260812 06:37:42.197599 30270 tablet_bootstrap.cc:492] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd: No bootstrap required, opened a new log
I20260812 06:37:42.197599 30265 tablet_bootstrap.cc:492] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963: No bootstrap required, opened a new log
I20260812 06:37:42.197795 30270 ts_tablet_manager.cc:1403] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd: Time spent bootstrapping tablet: real 0.005s	user 0.003s	sys 0.000s
I20260812 06:37:42.197795 30265 ts_tablet_manager.cc:1403] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963: Time spent bootstrapping tablet: real 0.006s	user 0.004s	sys 0.000s
I20260812 06:37:42.198482 30271 tablet_bootstrap.cc:654] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:42.199339 30271 log.cc:826] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:42.201004 30271 tablet_bootstrap.cc:492] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e: No bootstrap required, opened a new log
I20260812 06:37:42.200930 30265 raft_consensus.cc:359] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.200930 30270 raft_consensus.cc:359] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.201151 30265 raft_consensus.cc:385] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:42.201151 30270 raft_consensus.cc:385] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:42.201217 30270 raft_consensus.cc:740] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: e41fb6dd727e43db845c9e3b50a3c7bd, State: Initialized, Role: FOLLOWER
I20260812 06:37:42.201217 30265 raft_consensus.cc:740] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1bdd71d8ef3e4be29f4fd593a470e963, State: Initialized, Role: FOLLOWER
I20260812 06:37:42.201272 30271 ts_tablet_manager.cc:1403] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e: Time spent bootstrapping tablet: real 0.005s	user 0.004s	sys 0.000s
I20260812 06:37:42.201704 30265 consensus_queue.cc:260] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [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: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.201704 30270 consensus_queue.cc:260] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [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: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.202621 30270 ts_tablet_manager.cc:1434] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd: Time spent starting tablet: real 0.005s	user 0.005s	sys 0.000s
I20260812 06:37:42.202621 30265 ts_tablet_manager.cc:1434] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963: Time spent starting tablet: real 0.005s	user 0.005s	sys 0.000s
I20260812 06:37:42.202647 30030 heartbeater.cc:499] Master 127.27.6.254:44949 was elected leader, sending a full tablet report...
I20260812 06:37:42.203003 29808 heartbeater.cc:499] Master 127.27.6.254:44949 was elected leader, sending a full tablet report...
I20260812 06:37:42.204423 30271 raft_consensus.cc:359] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.204689 30271 raft_consensus.cc:385] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:42.204769 30271 raft_consensus.cc:740] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a22be6d0763f4b309ae42eb003d92d3e, State: Initialized, Role: FOLLOWER
I20260812 06:37:42.205152 30271 consensus_queue.cc:260] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [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: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.205859 30237 heartbeater.cc:499] Master 127.27.6.254:44949 was elected leader, sending a full tablet report...
I20260812 06:37:42.206599 30271 ts_tablet_manager.cc:1434] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e: Time spent starting tablet: real 0.005s	user 0.005s	sys 0.000s
W20260812 06:37:42.242092 29810 tablet.cc:2406] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963: Can't schedule compaction. Clean time has not been advanced past its initial value.
I20260812 06:37:42.290084 30279 raft_consensus.cc:493] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:42.290336 30279 raft_consensus.cc:515] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.291656 30279 leader_election.cc:290] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers a22be6d0763f4b309ae42eb003d92d3e (127.27.6.195:34841), 1bdd71d8ef3e4be29f4fd593a470e963 (127.27.6.193:34199)
I20260812 06:37:42.296380 30165 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "783e4920045849a387ef0b09f62b28f2" candidate_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a22be6d0763f4b309ae42eb003d92d3e" is_pre_election: true
I20260812 06:37:42.296376 29738 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "783e4920045849a387ef0b09f62b28f2" candidate_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" is_pre_election: true
I20260812 06:37:42.296741 30165 raft_consensus.cc:2468] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate e41fb6dd727e43db845c9e3b50a3c7bd in term 0.
I20260812 06:37:42.296758 29738 raft_consensus.cc:2468] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate e41fb6dd727e43db845c9e3b50a3c7bd in term 0.
I20260812 06:37:42.297312 29840 leader_election.cc:304] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [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: a22be6d0763f4b309ae42eb003d92d3e, e41fb6dd727e43db845c9e3b50a3c7bd; no voters: 
I20260812 06:37:42.297578 30279 raft_consensus.cc:2804] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Leader pre-election won for term 1
I20260812 06:37:42.297684 30279 raft_consensus.cc:493] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:42.297773 30279 raft_consensus.cc:3060] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:42.298857 30279 raft_consensus.cc:515] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.299363 30279 leader_election.cc:290] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [CANDIDATE]: Term 1 election: Requested vote from peers a22be6d0763f4b309ae42eb003d92d3e (127.27.6.195:34841), 1bdd71d8ef3e4be29f4fd593a470e963 (127.27.6.193:34199)
I20260812 06:37:42.299835 30165 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "783e4920045849a387ef0b09f62b28f2" candidate_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a22be6d0763f4b309ae42eb003d92d3e"
I20260812 06:37:42.299976 30165 raft_consensus.cc:3060] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:42.299980 29738 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "783e4920045849a387ef0b09f62b28f2" candidate_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1bdd71d8ef3e4be29f4fd593a470e963"
I20260812 06:37:42.300144 29738 raft_consensus.cc:3060] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:42.301262 29738 raft_consensus.cc:2468] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate e41fb6dd727e43db845c9e3b50a3c7bd in term 1.
I20260812 06:37:42.301566 30165 raft_consensus.cc:2468] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate e41fb6dd727e43db845c9e3b50a3c7bd in term 1.
I20260812 06:37:42.301733 29840 leader_election.cc:304] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [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: 1bdd71d8ef3e4be29f4fd593a470e963, e41fb6dd727e43db845c9e3b50a3c7bd; no voters: 
I20260812 06:37:42.301946 30279 raft_consensus.cc:2804] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:42.302220 30279 raft_consensus.cc:697] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [term 1 LEADER]: Becoming Leader. State: Replica: e41fb6dd727e43db845c9e3b50a3c7bd, State: Running, Role: LEADER
I20260812 06:37:42.302548 30279 consensus_queue.cc:237] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [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: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } }
I20260812 06:37:42.306228 29499 catalog_manager.cc:5719] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd reported cstate change: term changed from 0 to 1, leader changed from <none> to e41fb6dd727e43db845c9e3b50a3c7bd (127.27.6.194). New cstate: current_term: 1 leader_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "e41fb6dd727e43db845c9e3b50a3c7bd" member_type: VOTER last_known_addr { host: "127.27.6.194" port: 43351 } health_report { overall_health: HEALTHY } } }
I20260812 06:37:42.343981 30165 raft_consensus.cc:1275] T 783e4920045849a387ef0b09f62b28f2 P a22be6d0763f4b309ae42eb003d92d3e [term 1 FOLLOWER]: Refusing update from remote peer e41fb6dd727e43db845c9e3b50a3c7bd: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
I20260812 06:37:42.344031 29738 raft_consensus.cc:1275] T 783e4920045849a387ef0b09f62b28f2 P 1bdd71d8ef3e4be29f4fd593a470e963 [term 1 FOLLOWER]: Refusing update from remote peer e41fb6dd727e43db845c9e3b50a3c7bd: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
I20260812 06:37:42.344798 30286 consensus_queue.cc:1048] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [LEADER]: Connected to new peer: Peer: permanent_uuid: "a22be6d0763f4b309ae42eb003d92d3e" member_type: VOTER last_known_addr { host: "127.27.6.195" port: 34841 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:42.345037 30279 consensus_queue.cc:1048] T 783e4920045849a387ef0b09f62b28f2 P e41fb6dd727e43db845c9e3b50a3c7bd [LEADER]: Connected to new peer: Peer: permanent_uuid: "1bdd71d8ef3e4be29f4fd593a470e963" member_type: VOTER last_known_addr { host: "127.27.6.193" port: 34199 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:42.354815 30292 mvcc.cc:204] Tried to move back new op lower bound from 7317572248952848384 to 7317572248794411008. Current Snapshot: MvccSnapshot[applied={T|T < 7317572248952848384}]
I20260812 06:37:42.836454 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 29598
I20260812 06:37:42.859512 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 29817
I20260812 06:37:42.871213 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 30036
I20260812 06:37:42.885531 27675 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskHUK93v/build/debug/bin/kudu with pid 29455
2026-08-12T06:37:42Z chronyd exiting
[       OK ] VarcharItest.TestVarcharRangePartition (1663 ms)
[----------] 3 tests from VarcharItest (4539 ms total)

[----------] Global test environment tear-down
[==========] 3 tests from 1 test suite ran. (4540 ms total)
[  PASSED  ] 3 tests.
