[==========] Running 1 test from 1 test suite.
[----------] Global test environment set-up.
[----------] 1 test from MasterMigrationTest
[ RUN      ] MasterMigrationTest.TestEndToEndMigration
2026-08-12T08:03:31Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T08:03:31Z Disabled control of system clock
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 08:03:32.001065 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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.25.163.126:44541
--webserver_interface=127.25.163.126
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:37547
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.163.126:44541 with env {}
W20260812 08:03:32.102739 26265 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:32.102929 26265 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:32.102968 26265 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:32.106173 26265 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 08:03:32.106236 26265 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:32.106256 26265 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 08:03:32.106276 26265 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 08:03:32.110096 26265 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37547
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.25.163.126:44541
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.163.126:44541
--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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.25.163.126
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.26265
--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 08:03:32.111191 26265 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 08:03:32.112334 26265 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:32.117672 26278 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 08:03:32.117714 26284 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 08:03:32.117681 26280 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 08:03:32.118078 26265 server_base.cc:1061] running on GCE node
I20260812 08:03:32.118553 26265 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:32.119474 26265 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:32.120628 26265 hybrid_clock.cc:648] HybridClock initialized: now 1786521812120646 us; error 65 us; skew 500 ppm
I20260812 08:03:32.122399 26265 webserver.cc:533] Webserver started at http://127.25.163.126:37465/ using document root <none> and password file <none>
I20260812 08:03:32.122958 26265 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:32.123018 26265 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:32.123191 26265 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 08:03:32.124730 26265 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data/instance:
uuid: "8c3643d88dd74ce28cd29681978e235e"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.125253 26265 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal/instance:
uuid: "8c3643d88dd74ce28cd29681978e235e"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.128484 26265 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.002s	sys 0.001s
I20260812 08:03:32.130611 26294 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:32.131549 26265 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
I20260812 08:03:32.131659 26265 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
uuid: "8c3643d88dd74ce28cd29681978e235e"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.131742 26265 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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 08:03:32.155643 26265 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:32.156342 26265 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 08:03:32.156538 26265 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:32.163805 26265 rpc_server.cc:307] RPC server started. Bound to: 127.25.163.126:44541
I20260812 08:03:32.163815 26399 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.163.126:44541 every 8 connection(s)
I20260812 08:03:32.164796 26265 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data/info.pb
I20260812 08:03:32.165937 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 26265
I20260812 08:03:32.166082 26253 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal/instance
I20260812 08:03:32.167697 26402 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 08:03:32.173414 26402 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Bootstrap starting.
I20260812 08:03:32.175671 26402 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Neither blocks nor log segments found. Creating new log.
I20260812 08:03:32.176474 26402 log.cc:826] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:32.178077 26402 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: No bootstrap required, opened a new log
I20260812 08:03:32.180301 26402 raft_consensus.cc:359] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } }
I20260812 08:03:32.180462 26402 raft_consensus.cc:385] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 08:03:32.180569 26402 raft_consensus.cc:740] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8c3643d88dd74ce28cd29681978e235e, State: Initialized, Role: FOLLOWER
I20260812 08:03:32.180966 26402 consensus_queue.cc:260] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [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: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } }
I20260812 08:03:32.181104 26402 raft_consensus.cc:399] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:32.181170 26402 raft_consensus.cc:493] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:32.181274 26402 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 0 FOLLOWER]: Advancing to term 1
I20260812 08:03:32.182150 26402 raft_consensus.cc:515] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } }
I20260812 08:03:32.182484 26402 leader_election.cc:304] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [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: 8c3643d88dd74ce28cd29681978e235e; no voters: 
I20260812 08:03:32.182794 26402 leader_election.cc:290] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 08:03:32.182874 26410 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Leader election won for term 1
I20260812 08:03:32.183096 26410 raft_consensus.cc:697] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 LEADER]: Becoming Leader. State: Replica: 8c3643d88dd74ce28cd29681978e235e, State: Running, Role: LEADER
I20260812 08:03:32.183415 26410 consensus_queue.cc:237] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [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: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } }
I20260812 08:03:32.183944 26402 sys_catalog.cc:565] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: configured and running, proceeding with master startup.
I20260812 08:03:32.184947 26419 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } }
I20260812 08:03:32.185076 26419 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:32.185235 26416 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8c3643d88dd74ce28cd29681978e235e. Latest consensus state: current_term: 1 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } }
I20260812 08:03:32.185402 26416 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: LEADER
W20260812 08:03:32.188813 26436 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 08:03:32.188877 26436 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 08:03:32.188951 26429 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 08:03:32.189875 26429 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 08:03:32.194368 26429 catalog_manager.cc:1383] Generated new cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:32.194478 26429 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 08:03:32.206996 26429 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 08:03:32.208086 26429 catalog_manager.cc:1540] Loading token signing keys...
I20260812 08:03:32.226653 26429 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Generated new TSK 0
I20260812 08:03:32.227330 26429 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 08:03:32.231856 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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.0.0.1:0
--local_ip_for_outbound_sockets=127.0.0.1
--webserver_interface=127.0.0.1
--webserver_port=0
--tserver_master_addrs=127.25.163.126:44541
--builtin_ntp_servers=127.0.0.1:37547
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
W20260812 08:03:32.333812 26444 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:32.334007 26444 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:32.334050 26444 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:32.337363 26444 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:32.337473 26444 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.0.1
I20260812 08:03:32.341183 26444 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37547
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.0.0.1:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.0.1
--webserver_port=0
--tserver_master_addrs=127.25.163.126:44541
--never_fsync=true
--heap_profile_path=/tmp/kudu.26444
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.0.0.1

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 08:03:32.342213 26444 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 08:03:32.343295 26444 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:32.349452 26455 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 08:03:32.349594 26459 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 08:03:32.349469 26454 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 08:03:32.349982 26444 server_base.cc:1061] running on GCE node
I20260812 08:03:32.350337 26444 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:32.350919 26444 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:32.352092 26444 hybrid_clock.cc:648] HybridClock initialized: now 1786521812352072 us; error 31 us; skew 500 ppm
I20260812 08:03:32.353801 26444 webserver.cc:533] Webserver started at http://127.0.0.1:37825/ using document root <none> and password file <none>
I20260812 08:03:32.354354 26444 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:32.354409 26444 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:32.354580 26444 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 08:03:32.356034 26444 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data/instance:
uuid: "1adf4994a3104b799f8ba820093099ba"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.356470 26444 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal/instance:
uuid: "1adf4994a3104b799f8ba820093099ba"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.359594 26444 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
I20260812 08:03:32.361598 26467 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:32.362519 26444 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:32.362617 26444 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
uuid: "1adf4994a3104b799f8ba820093099ba"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.362699 26444 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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 08:03:32.390518 26444 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:32.391207 26444 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 08:03:32.391379 26444 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:32.391950 26444 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 08:03:32.392942 26444 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 08:03:32.392989 26444 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:32.393042 26444 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 08:03:32.393069 26444 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:32.402910 26444 rpc_server.cc:307] RPC server started. Bound to: 127.0.0.1:38983
I20260812 08:03:32.402951 26680 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.0.1:38983 every 8 connection(s)
I20260812 08:03:32.403844 26444 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data/info.pb
I20260812 08:03:32.408005 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 26444
I20260812 08:03:32.408103 26253 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal/instance
I20260812 08:03:32.411871 26681 heartbeater.cc:344] Connected to a master server at 127.25.163.126:44541
I20260812 08:03:32.412068 26681 heartbeater.cc:461] Registering TS with master...
I20260812 08:03:32.412489 26681 heartbeater.cc:507] Master 127.25.163.126:44541 requested a full tablet report, sending...
I20260812 08:03:32.413776 26329 ts_manager.cc:194] Registered new tserver with Master: 1adf4994a3104b799f8ba820093099ba (127.0.0.1:38983)
I20260812 08:03:32.414916 26329 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:48489
I20260812 08:03:32.422294 26253 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 08:03:32.432708 26329 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:51030:
name: "test"
schema {
  columns {
    name: "key"
    type: INT32
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
    columns {
      name: "key"
    }
  }
}
I20260812 08:03:32.448246 26555 tablet_service.cc:1511] Processing CreateTablet for tablet 9ec4601b6b43479189d08680a0d27666 (DEFAULT_TABLE table=test [id=2660fb430cdc45898b90cf762082f54c]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 08:03:32.449169 26555 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9ec4601b6b43479189d08680a0d27666. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 08:03:32.454259 26697 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Bootstrap starting.
I20260812 08:03:32.456153 26697 tablet_bootstrap.cc:654] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Neither blocks nor log segments found. Creating new log.
I20260812 08:03:32.456847 26697 log.cc:826] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:32.458477 26697 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: No bootstrap required, opened a new log
I20260812 08:03:32.458670 26697 ts_tablet_manager.cc:1403] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Time spent bootstrapping tablet: real 0.005s	user 0.003s	sys 0.000s
I20260812 08:03:32.460955 26697 raft_consensus.cc:359] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:32.461162 26697 raft_consensus.cc:385] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 08:03:32.461225 26697 raft_consensus.cc:740] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Initialized, Role: FOLLOWER
I20260812 08:03:32.461632 26697 consensus_queue.cc:260] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:32.461751 26697 raft_consensus.cc:399] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:32.461812 26697 raft_consensus.cc:493] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:32.461915 26697 raft_consensus.cc:3060] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Advancing to term 1
I20260812 08:03:32.462673 26697 raft_consensus.cc:515] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:32.462991 26697 leader_election.cc:304] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [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: 1adf4994a3104b799f8ba820093099ba; no voters: 
I20260812 08:03:32.463269 26697 leader_election.cc:290] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 08:03:32.463639 26701 raft_consensus.cc:2804] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Leader election won for term 1
I20260812 08:03:32.463826 26681 heartbeater.cc:499] Master 127.25.163.126:44541 was elected leader, sending a full tablet report...
I20260812 08:03:32.463856 26701 raft_consensus.cc:697] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 LEADER]: Becoming Leader. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Running, Role: LEADER
I20260812 08:03:32.464071 26697 ts_tablet_manager.cc:1434] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Time spent starting tablet: real 0.005s	user 0.006s	sys 0.000s
I20260812 08:03:32.464171 26701 consensus_queue.cc:237] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:32.467757 26329 catalog_manager.cc:5719] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba reported cstate change: term changed from 0 to 1, leader changed from <none> to 1adf4994a3104b799f8ba820093099ba (127.0.0.1). New cstate: current_term: 1 leader_uuid: "1adf4994a3104b799f8ba820093099ba" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } health_report { overall_health: HEALTHY } } }
I20260812 08:03:32.472249 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 26444
I20260812 08:03:32.505808 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 26265
I20260812 08:03:32.625195 26711 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:32.625409 26711 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:32.637178 26711 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/data/instance:
uuid: "3696b46ea81a4ad785cc7432f69fb1a0"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.640522 26711 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal/instance:
uuid: "3696b46ea81a4ad785cc7432f69fb1a0"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.649708 26711 fs_manager.cc:696] Time spent creating directory manager: real 0.009s	user 0.004s	sys 0.000s
I20260812 08:03:32.769729 26720 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:32.769932 26720 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:32.886840 26726 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:32.887068 26726 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:32.892400 26726 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/data/instance:
uuid: "dda6bfc86784449db64dd2dbf644ece4"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.895728 26726 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal/instance:
uuid: "dda6bfc86784449db64dd2dbf644ece4"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:32.904672 26726 fs_manager.cc:696] Time spent creating directory manager: real 0.009s	user 0.004s	sys 0.000s
I20260812 08:03:33.024909 26737 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:33.025146 26737 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:33.138844 26745 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:33.139132 26745 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:33.144711 26745 fs_manager.cc:714] Time spent opening directory manager: real 0.004s	user 0.006s	sys 0.001s
I20260812 08:03:33.145160 26745 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
uuid: "8c3643d88dd74ce28cd29681978e235e"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:33.145597 26745 tool_action_local_replica.cc:573] Rewriting Raft config of tablet: 00000000000000000000000000000000
I20260812 08:03:33.148537 26745 tool_action_local_replica.cc:538] Backed up old consensus metadata to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal/consensus-meta/00000000000000000000000000000000.pre_rewrite.1786521813145647
I20260812 08:03:33.159789 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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.25.163.126:44541
--webserver_interface=127.25.163.126
--webserver_port=37465
--builtin_ntp_servers=127.0.0.1:37547
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.163.126:44541 with env {}
W20260812 08:03:33.259567 26757 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:33.259740 26757 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:33.259778 26757 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:33.262874 26757 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 08:03:33.262920 26757 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:33.262938 26757 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 08:03:33.262959 26757 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 08:03:33.266608 26757 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37547
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.25.163.126:44541
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.163.126:44541
--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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.25.163.126
--webserver_port=37465
--never_fsync=true
--heap_profile_path=/tmp/kudu.26757
--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 08:03:33.267459 26757 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 08:03:33.268414 26757 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:33.273321 26764 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 08:03:33.273327 26770 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 08:03:33.273335 26763 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 08:03:33.273590 26757 server_base.cc:1061] running on GCE node
I20260812 08:03:33.274019 26757 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:33.274829 26757 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:33.275976 26757 hybrid_clock.cc:648] HybridClock initialized: now 1786521813275962 us; error 30 us; skew 500 ppm
I20260812 08:03:33.277609 26757 webserver.cc:533] Webserver started at http://127.25.163.126:37465/ using document root <none> and password file <none>
I20260812 08:03:33.278097 26757 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:33.278152 26757 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:33.281046 26757 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 08:03:33.282719 26778 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:33.283576 26757 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.000s	sys 0.001s
I20260812 08:03:33.283684 26757 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
uuid: "8c3643d88dd74ce28cd29681978e235e"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:33.284010 26757 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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 08:03:33.297003 26757 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:33.297559 26757 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 08:03:33.297709 26757 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:33.304296 26757 rpc_server.cc:307] RPC server started. Bound to: 127.25.163.126:44541
I20260812 08:03:33.304325 26872 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.163.126:44541 every 8 connection(s)
I20260812 08:03:33.305279 26757 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data/info.pb
I20260812 08:03:33.309537 26873 sys_catalog.cc:263] Verifying existing consensus state
W20260812 08:03:33.310338 26873 sys_catalog.cc:213] For a single master config, multiple peers found in on-disk Raft config! Supply correct list of masters in --master_addresses flag.
I20260812 08:03:33.311054 26873 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Bootstrap starting.
I20260812 08:03:33.314934 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 26757
I20260812 08:03:33.315394 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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.0.0.1:38983
--local_ip_for_outbound_sockets=127.0.0.1
--tserver_master_addrs=127.25.163.126:44541
--webserver_port=37825
--webserver_interface=127.0.0.1
--builtin_ntp_servers=127.0.0.1:37547
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 08:03:33.326220 26873 log.cc:826] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:33.334002 26873 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Bootstrap replayed 1/1 log segments. Stats: ops{read=7 overwritten=0 applied=7 ignored=0} inserts{seen=5 ignored=0} mutations{seen=2 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 08:03:33.334357 26873 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Bootstrap complete.
I20260812 08:03:33.337749 26873 raft_consensus.cc:359] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:33.338078 26873 raft_consensus.cc:740] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8c3643d88dd74ce28cd29681978e235e, State: Initialized, Role: FOLLOWER
I20260812 08:03:33.338590 26873 consensus_queue.cc:260] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 7, Last appended: 1.7, Last appended by leader: 7, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:33.339053 26879 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:33.339211 26879 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:33.339679 26873 sys_catalog.cc:565] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: configured and running, proceeding with master startup.
I20260812 08:03:33.344408 26903 catalog_manager.cc:1295] Loaded cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:33.344472 26903 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: loading cluster ID for follower catalog manager: success
I20260812 08:03:33.346851 26903 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: acquiring CA information for follower catalog manager: success
I20260812 08:03:33.348114 26903 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
W20260812 08:03:33.417131 26875 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:33.417320 26875 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:33.417377 26875 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:33.420552 26875 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:33.420658 26875 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.0.1
I20260812 08:03:33.424292 26875 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37547
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.0.0.1:38983
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.0.1
--webserver_port=37825
--tserver_master_addrs=127.25.163.126:44541
--never_fsync=true
--heap_profile_path=/tmp/kudu.26875
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.0.0.1

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 08:03:33.425261 26875 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 08:03:33.426321 26875 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:33.432125 26913 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 08:03:33.432163 26918 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 08:03:33.432408 26875 server_base.cc:1061] running on GCE node
W20260812 08:03:33.432431 26914 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 08:03:33.432757 26875 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:33.433281 26875 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:33.434449 26875 hybrid_clock.cc:648] HybridClock initialized: now 1786521813434437 us; error 27 us; skew 500 ppm
I20260812 08:03:33.436017 26875 webserver.cc:533] Webserver started at http://127.0.0.1:37825/ using document root <none> and password file <none>
I20260812 08:03:33.436528 26875 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:33.436595 26875 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:33.439705 26875 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:33.441538 26926 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:33.442482 26875 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:33.442569 26875 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
uuid: "1adf4994a3104b799f8ba820093099ba"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:33.442925 26875 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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 08:03:33.455379 26875 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:33.455971 26875 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 08:03:33.456130 26875 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:33.456679 26875 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 08:03:33.457875 26937 ts_tablet_manager.cc:542] Loading tablet metadata (0/1 complete)
I20260812 08:03:33.461145 26875 ts_tablet_manager.cc:585] Loaded tablet metadata (1 total tablets, 1 live tablets)
I20260812 08:03:33.461205 26875 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.004s	user 0.000s	sys 0.000s
I20260812 08:03:33.461254 26875 ts_tablet_manager.cc:600] Registering tablets (0/1 complete)
I20260812 08:03:33.462693 26875 ts_tablet_manager.cc:616] Registered 1 tablets
I20260812 08:03:33.462749 26875 ts_tablet_manager.cc:595] Time spent register tablets: real 0.002s	user 0.000s	sys 0.000s
I20260812 08:03:33.463089 26937 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Bootstrap starting.
I20260812 08:03:33.474931 26875 rpc_server.cc:307] RPC server started. Bound to: 127.0.0.1:38983
I20260812 08:03:33.475294 27135 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.0.1:38983 every 8 connection(s)
I20260812 08:03:33.476298 26875 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data/info.pb
I20260812 08:03:33.480926 26937 log.cc:826] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:33.481506 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 26875
I20260812 08:03:33.483405 26937 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Bootstrap replayed 1/1 log segments. Stats: ops{read=1 overwritten=0 applied=1 ignored=0} inserts{seen=0 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 08:03:33.483786 26937 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Bootstrap complete.
I20260812 08:03:33.485220 26937 ts_tablet_manager.cc:1403] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Time spent bootstrapping tablet: real 0.022s	user 0.012s	sys 0.010s
I20260812 08:03:33.488816 26937 raft_consensus.cc:359] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:33.489245 26937 raft_consensus.cc:740] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Initialized, Role: FOLLOWER
I20260812 08:03:33.489718 26937 consensus_queue.cc:260] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 1, Last appended: 1.1, Last appended by leader: 1, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:33.489873 26937 raft_consensus.cc:399] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:33.489944 26937 raft_consensus.cc:493] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:33.490058 26937 raft_consensus.cc:3060] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Advancing to term 2
I20260812 08:03:33.491899 27137 heartbeater.cc:344] Connected to a master server at 127.25.163.126:44541
I20260812 08:03:33.492031 26937 raft_consensus.cc:515] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:33.492138 27137 heartbeater.cc:461] Registering TS with master...
I20260812 08:03:33.492369 26937 leader_election.cc:304] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 2 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: 1adf4994a3104b799f8ba820093099ba; no voters: 
I20260812 08:03:33.492664 26937 leader_election.cc:290] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 2 election: Requested vote from peers 
I20260812 08:03:33.492746 27137 heartbeater.cc:507] Master 127.25.163.126:44541 requested a full tablet report, sending...
I20260812 08:03:33.492796 27153 raft_consensus.cc:2804] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 FOLLOWER]: Leader election won for term 2
I20260812 08:03:33.493072 27153 raft_consensus.cc:697] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 LEADER]: Becoming Leader. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Running, Role: LEADER
I20260812 08:03:33.493546 26937 ts_tablet_manager.cc:1434] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Time spent starting tablet: real 0.008s	user 0.006s	sys 0.002s
I20260812 08:03:33.493948 27153 consensus_queue.cc:237] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 1, Committed index: 1, Last appended: 1.1, Last appended by leader: 1, Current term: 2, Majority size: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:33.497861 26812 ts_manager.cc:194] Registered new tserver with Master: 1adf4994a3104b799f8ba820093099ba (127.0.0.1:38983)
I20260812 08:03:33.505548 26253 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 08:03:33.612985 27163 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:33.613189 27163 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:33.617496 27163 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.003s	sys 0.001s
I20260812 08:03:33.619602 27174 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:33.620368 27163 fs_report.cc:389] FS layout report
--------------------
wal directory: 
metadata directory: 
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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 08:03:33.620745 27163 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:33.620792 27163 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal
uuid: "3696b46ea81a4ad785cc7432f69fb1a0"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
W20260812 08:03:33.626147 27181 tool_action_local_replica.cc:343] Start to copy tablet 00000000000000000000000000000000
I20260812 08:03:33.626947 27181 tablet_copy_client.cc:323] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: tablet copy: Beginning tablet copy session from remote peer at address 127.25.163.126:44541
I20260812 08:03:33.634522 26847 tablet_copy_service.cc:140] P 8c3643d88dd74ce28cd29681978e235e: Received BeginTabletCopySession request for tablet 00000000000000000000000000000000 from peer 3696b46ea81a4ad785cc7432f69fb1a0 ({username='slave'} at 127.0.0.1:51062)
I20260812 08:03:33.634673 26847 tablet_copy_service.cc:161] P 8c3643d88dd74ce28cd29681978e235e: Beginning new tablet copy session on tablet 00000000000000000000000000000000 from peer 3696b46ea81a4ad785cc7432f69fb1a0 at {username='slave'} at 127.0.0.1:51062: session id = 3696b46ea81a4ad785cc7432f69fb1a0-00000000000000000000000000000000
I20260812 08:03:33.635751 26847 tablet_copy_source_session.cc:215] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Tablet Copy: opened 0 blocks and 1 log segments
I20260812 08:03:33.639317 27181 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total
I20260812 08:03:33.647099 27181 tablet_copy_client.cc:806] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: tablet copy: Starting download of 0 data blocks...
I20260812 08:03:33.648546 27181 tablet_copy_client.cc:670] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: tablet copy: Starting download of 1 WAL segments...
I20260812 08:03:33.651875 27181 tablet_copy_client.cc:538] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: tablet copy: Tablet Copy complete. Replacing tablet superblock.
I20260812 08:03:33.656008 27181 tool_action_local_replica.cc:343] Tablet 00000000000000000000000000000000 copy succeed.
I20260812 08:03:33.656055 27181 tool_action_local_replica.cc:343] 1/1 tablets, 2955 bytes copied, include 0 failed tablets.
I20260812 08:03:33.656576 26847 tablet_copy_service.cc:342] P 8c3643d88dd74ce28cd29681978e235e: Request end of tablet copy session 3696b46ea81a4ad785cc7432f69fb1a0-00000000000000000000000000000000 received from {username='slave'} at 127.0.0.1:51062
I20260812 08:03:33.656674 26847 tablet_copy_service.cc:434] P 8c3643d88dd74ce28cd29681978e235e: ending tablet copy session 3696b46ea81a4ad785cc7432f69fb1a0-00000000000000000000000000000000 on tablet 00000000000000000000000000000000 with peer 3696b46ea81a4ad785cc7432f69fb1a0
I20260812 08:03:33.774698 27198 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:33.774983 27198 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:33.779307 27198 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.005s	sys 0.000s
I20260812 08:03:33.781312 27208 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:33.781989 27198 fs_report.cc:389] FS layout report
--------------------
wal directory: 
metadata directory: 
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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 08:03:33.782372 27198 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
I20260812 08:03:33.782419 27198 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal
uuid: "dda6bfc86784449db64dd2dbf644ece4"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
W20260812 08:03:33.787533 27214 tool_action_local_replica.cc:343] Start to copy tablet 00000000000000000000000000000000
I20260812 08:03:33.788341 27214 tablet_copy_client.cc:323] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: tablet copy: Beginning tablet copy session from remote peer at address 127.25.163.126:44541
I20260812 08:03:33.795290 26847 tablet_copy_service.cc:140] P 8c3643d88dd74ce28cd29681978e235e: Received BeginTabletCopySession request for tablet 00000000000000000000000000000000 from peer dda6bfc86784449db64dd2dbf644ece4 ({username='slave'} at 127.0.0.1:51074)
I20260812 08:03:33.795375 26847 tablet_copy_service.cc:161] P 8c3643d88dd74ce28cd29681978e235e: Beginning new tablet copy session on tablet 00000000000000000000000000000000 from peer dda6bfc86784449db64dd2dbf644ece4 at {username='slave'} at 127.0.0.1:51074: session id = dda6bfc86784449db64dd2dbf644ece4-00000000000000000000000000000000
I20260812 08:03:33.796093 26847 tablet_copy_source_session.cc:215] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Tablet Copy: opened 0 blocks and 1 log segments
I20260812 08:03:33.798918 27214 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total
I20260812 08:03:33.806368 27214 tablet_copy_client.cc:806] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: tablet copy: Starting download of 0 data blocks...
I20260812 08:03:33.807822 27214 tablet_copy_client.cc:670] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: tablet copy: Starting download of 1 WAL segments...
I20260812 08:03:33.811035 27214 tablet_copy_client.cc:538] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: tablet copy: Tablet Copy complete. Replacing tablet superblock.
I20260812 08:03:33.815387 27214 tool_action_local_replica.cc:343] Tablet 00000000000000000000000000000000 copy succeed.
I20260812 08:03:33.815435 27214 tool_action_local_replica.cc:343] 1/1 tablets, 2955 bytes copied, include 0 failed tablets.
I20260812 08:03:33.815898 26847 tablet_copy_service.cc:342] P 8c3643d88dd74ce28cd29681978e235e: Request end of tablet copy session dda6bfc86784449db64dd2dbf644ece4-00000000000000000000000000000000 received from {username='slave'} at 127.0.0.1:51074
I20260812 08:03:33.815984 26847 tablet_copy_service.cc:434] P 8c3643d88dd74ce28cd29681978e235e: ending tablet copy session dda6bfc86784449db64dd2dbf644ece4-00000000000000000000000000000000 on tablet 00000000000000000000000000000000 with peer dda6bfc86784449db64dd2dbf644ece4
I20260812 08:03:33.825857 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 26875
I20260812 08:03:33.850092 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 26757
2026-08-12T08:03:33Z chronyd exiting
2026-08-12T08:03:33Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T08:03:33Z Disabled control of system clock
I20260812 08:03:33.884315 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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.25.163.126:44541
--webserver_interface=127.25.163.126
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:44777
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911 with env {}
W20260812 08:03:33.984390 27235 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:33.984575 27235 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:33.984612 27235 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:33.987859 27235 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 08:03:33.987917 27235 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:33.987936 27235 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 08:03:33.987953 27235 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 08:03:33.991667 27235 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:44777
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.163.126:44541
--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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.25.163.126
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.27235
--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 08:03:33.992684 27235 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 08:03:33.993769 27235 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:33.999310 27249 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 08:03:33.999305 27244 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 08:03:33.999368 27235 server_base.cc:1061] running on GCE node
W20260812 08:03:33.999516 27245 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 08:03:34.000015 27235 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:34.000864 27235 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:34.002008 27235 hybrid_clock.cc:648] HybridClock initialized: now 1786521814001981 us; error 40 us; skew 500 ppm
I20260812 08:03:34.003664 27235 webserver.cc:533] Webserver started at http://127.25.163.126:43271/ using document root <none> and password file <none>
I20260812 08:03:34.004194 27235 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:34.004251 27235 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:34.007377 27235 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:34.009191 27260 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:34.010064 27235 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:34.010164 27235 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
uuid: "8c3643d88dd74ce28cd29681978e235e"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:34.010524 27235 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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 08:03:34.025885 27235 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:34.026437 27235 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 08:03:34.026587 27235 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:34.033370 27235 rpc_server.cc:307] RPC server started. Bound to: 127.25.163.126:44541
I20260812 08:03:34.033376 27359 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.163.126:44541 every 8 connection(s)
I20260812 08:03:34.034320 27235 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/data/info.pb
I20260812 08:03:34.038017 27360 sys_catalog.cc:263] Verifying existing consensus state
I20260812 08:03:34.038815 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 27235
I20260812 08:03:34.038899 26253 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-0/wal/instance
I20260812 08:03:34.039394 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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
master
run
--ipki_ca_key_size=768
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.163.125:35049
--webserver_interface=127.25.163.125
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:44777
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911 with env {}
I20260812 08:03:34.039559 27360 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Bootstrap starting.
I20260812 08:03:34.053973 27360 log.cc:826] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:34.061041 27360 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Bootstrap replayed 1/1 log segments. Stats: ops{read=7 overwritten=0 applied=7 ignored=0} inserts{seen=5 ignored=0} mutations{seen=2 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 08:03:34.061389 27360 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Bootstrap complete.
I20260812 08:03:34.064577 27360 raft_consensus.cc:359] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:34.064879 27360 raft_consensus.cc:740] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8c3643d88dd74ce28cd29681978e235e, State: Initialized, Role: FOLLOWER
I20260812 08:03:34.065367 27360 consensus_queue.cc:260] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 7, Last appended: 1.7, Last appended by leader: 7, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:34.065763 27368 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:34.065891 27368 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:34.066395 27360 sys_catalog.cc:565] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: configured and running, proceeding with master startup.
I20260812 08:03:34.070900 27389 catalog_manager.cc:1295] Loaded cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:34.070955 27389 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: loading cluster ID for follower catalog manager: success
I20260812 08:03:34.073408 27389 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: acquiring CA information for follower catalog manager: success
I20260812 08:03:34.074630 27389 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 08:03:34.083087 27235 master_runner.cc:187] Error getting master registration for 127.25.163.125:35049: Network error: Client connection negotiation failed: client connection to 127.25.163.125:35049: connect: Connection refused (error 111), 
I20260812 08:03:34.085529 27235 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 08:03:34.144548 27364 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:34.144747 27364 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:34.144784 27364 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:34.148008 27364 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 08:03:34.148059 27364 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:34.148078 27364 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 08:03:34.148094 27364 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 08:03:34.151784 27364 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:44777
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal
--ipki_ca_key_size=768
--master_addresses=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.163.125:35049
--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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/data/info.pb
--webserver_interface=127.25.163.125
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.27364
--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 08:03:34.152752 27364 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 08:03:34.153852 27364 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:34.159047 27400 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 08:03:34.159065 27408 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 08:03:34.159036 27402 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 08:03:34.159233 27364 server_base.cc:1061] running on GCE node
I20260812 08:03:34.159757 27364 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:34.160566 27364 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:34.161710 27364 hybrid_clock.cc:648] HybridClock initialized: now 1786521814161695 us; error 32 us; skew 500 ppm
I20260812 08:03:34.163298 27364 webserver.cc:533] Webserver started at http://127.25.163.125:42417/ using document root <none> and password file <none>
I20260812 08:03:34.163825 27364 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:34.163884 27364 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:34.167098 27364 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.000s	sys 0.003s
I20260812 08:03:34.169040 27422 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:34.170029 27364 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.001s
I20260812 08:03:34.170130 27364 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal
uuid: "3696b46ea81a4ad785cc7432f69fb1a0"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:34.170492 27364 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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 08:03:34.182195 27364 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:34.182772 27364 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 08:03:34.182933 27364 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:34.190181 27364 rpc_server.cc:307] RPC server started. Bound to: 127.25.163.125:35049
I20260812 08:03:34.190230 27524 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.163.125:35049 every 8 connection(s)
I20260812 08:03:34.191273 27364 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/data/info.pb
I20260812 08:03:34.195010 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 27364
I20260812 08:03:34.195114 26253 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-1/wal/instance
I20260812 08:03:34.195652 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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
master
run
--ipki_ca_key_size=768
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.163.124:41911
--webserver_interface=127.25.163.124
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:44777
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911 with env {}
I20260812 08:03:34.196538 27525 sys_catalog.cc:263] Verifying existing consensus state
I20260812 08:03:34.198699 27525 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: Bootstrap starting.
I20260812 08:03:34.206848 27525 log.cc:826] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:34.215911 27525 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: Bootstrap replayed 1/1 log segments. Stats: ops{read=7 overwritten=0 applied=7 ignored=0} inserts{seen=5 ignored=0} mutations{seen=2 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 08:03:34.216284 27525 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: Bootstrap complete.
I20260812 08:03:34.220093 27525 raft_consensus.cc:359] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:34.220503 27525 raft_consensus.cc:740] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3696b46ea81a4ad785cc7432f69fb1a0, State: Initialized, Role: FOLLOWER
I20260812 08:03:34.221043 27525 consensus_queue.cc:260] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 7, Last appended: 1.7, Last appended by leader: 7, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:34.221553 27535 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:34.221751 27535 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:34.222190 27525 sys_catalog.cc:565] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 08:03:34.227876 27555 catalog_manager.cc:1295] Loaded cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:34.228008 27555 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: loading cluster ID for follower catalog manager: success
I20260812 08:03:34.230450 27555 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: acquiring CA information for follower catalog manager: success
I20260812 08:03:34.231806 27555 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 08:03:34.249461 27364 master_runner.cc:187] Error getting master registration for 127.25.163.124:41911: Network error: Client connection negotiation failed: client connection to 127.25.163.124:41911: connect: Connection refused (error 111), 
I20260812 08:03:34.251546 27364 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 08:03:34.311299 27527 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:34.311511 27527 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:34.311550 27527 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:34.314857 27527 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 08:03:34.314913 27527 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:34.314932 27527 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 08:03:34.314949 27527 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 08:03:34.318743 27527 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:44777
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal
--ipki_ca_key_size=768
--master_addresses=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.163.124:41911
--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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/data/info.pb
--webserver_interface=127.25.163.124
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.27527
--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 08:03:34.319816 27527 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 08:03:34.320963 27527 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:34.326370 27571 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 08:03:34.326349 27570 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 08:03:34.326350 27573 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 08:03:34.326829 27527 server_base.cc:1061] running on GCE node
I20260812 08:03:34.327279 27527 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:34.328140 27527 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:34.329305 27527 hybrid_clock.cc:648] HybridClock initialized: now 1786521814329285 us; error 35 us; skew 500 ppm
I20260812 08:03:34.331130 27527 webserver.cc:533] Webserver started at http://127.25.163.124:41529/ using document root <none> and password file <none>
I20260812 08:03:34.331771 27527 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:34.331841 27527 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:34.335248 27527 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 08:03:34.337230 27591 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:34.338267 27527 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:34.338375 27527 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal
uuid: "dda6bfc86784449db64dd2dbf644ece4"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:34.338732 27527 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-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 08:03:34.349704 27527 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:34.350262 27527 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 08:03:34.350415 27527 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:34.358158 27527 rpc_server.cc:307] RPC server started. Bound to: 127.25.163.124:41911
I20260812 08:03:34.358175 27688 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.163.124:41911 every 8 connection(s)
I20260812 08:03:34.359186 27527 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/data/info.pb
I20260812 08:03:34.362929 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 27527
I20260812 08:03:34.363027 26253 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/master-2/wal/instance
I20260812 08:03:34.364117 27690 sys_catalog.cc:263] Verifying existing consensus state
I20260812 08:03:34.366222 27690 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: Bootstrap starting.
I20260812 08:03:34.373581 27690 log.cc:826] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:34.382328 27690 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: Bootstrap replayed 1/1 log segments. Stats: ops{read=7 overwritten=0 applied=7 ignored=0} inserts{seen=5 ignored=0} mutations{seen=2 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 08:03:34.382681 27690 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: Bootstrap complete.
I20260812 08:03:34.386041 27690 raft_consensus.cc:359] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:34.386451 27690 raft_consensus.cc:740] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: dda6bfc86784449db64dd2dbf644ece4, State: Initialized, Role: FOLLOWER
I20260812 08:03:34.386945 27690 consensus_queue.cc:260] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 7, Last appended: 1.7, Last appended by leader: 7, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:34.387400 27696 sys_catalog.cc:455] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:34.387516 27696 sys_catalog.cc:458] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:34.388149 27690 sys_catalog.cc:565] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 08:03:34.393781 27713 catalog_manager.cc:1295] Loaded cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:34.393832 27713 catalog_manager.cc:1588] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: loading cluster ID for follower catalog manager: success
I20260812 08:03:34.396065 27713 catalog_manager.cc:1610] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: acquiring CA information for follower catalog manager: success
I20260812 08:03:34.397364 27713 catalog_manager.cc:1638] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 08:03:34.411664 27527 master_runner.cc:264] No leader master found from master dda6bfc86784449db64dd2dbf644ece4
I20260812 08:03:34.411748 27527 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
I20260812 08:03:35.786019 27723 raft_consensus.cc:493] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 1 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 08:03:35.786218 27723 raft_consensus.cc:515] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 1 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:35.787423 27723 leader_election.cc:290] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [CANDIDATE]: Term 2 pre-election: Requested pre-vote from peers 8c3643d88dd74ce28cd29681978e235e (127.25.163.126:44541), dda6bfc86784449db64dd2dbf644ece4 (127.25.163.124:41911)
I20260812 08:03:35.787984 27306 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "8c3643d88dd74ce28cd29681978e235e" is_pre_election: true
I20260812 08:03:35.788348 27306 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 3696b46ea81a4ad785cc7432f69fb1a0 in term 1.
I20260812 08:03:35.788791 27424 leader_election.cc:304] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [CANDIDATE]: Term 2 pre-election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 3696b46ea81a4ad785cc7432f69fb1a0, 8c3643d88dd74ce28cd29681978e235e; no voters: 
I20260812 08:03:35.789073 27723 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 1 FOLLOWER]: Leader pre-election won for term 2
I20260812 08:03:35.789201 27723 raft_consensus.cc:493] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 1 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 08:03:35.789304 27723 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 1 FOLLOWER]: Advancing to term 2
I20260812 08:03:35.791081 27723 raft_consensus.cc:515] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 2 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:35.791460 27723 leader_election.cc:290] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [CANDIDATE]: Term 2 election: Requested vote from peers 8c3643d88dd74ce28cd29681978e235e (127.25.163.126:44541), dda6bfc86784449db64dd2dbf644ece4 (127.25.163.124:41911)
I20260812 08:03:35.791788 27306 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "8c3643d88dd74ce28cd29681978e235e"
I20260812 08:03:35.791800 27648 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "dda6bfc86784449db64dd2dbf644ece4" is_pre_election: true
I20260812 08:03:35.791848 27647 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "dda6bfc86784449db64dd2dbf644ece4"
I20260812 08:03:35.791941 27306 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 1 FOLLOWER]: Advancing to term 2
I20260812 08:03:35.792120 27648 raft_consensus.cc:2468] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 1 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 3696b46ea81a4ad785cc7432f69fb1a0 in term 1.
I20260812 08:03:35.793745 27306 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 2 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 3696b46ea81a4ad785cc7432f69fb1a0 in term 2.
I20260812 08:03:35.794054 27424 leader_election.cc:304] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [CANDIDATE]: Term 2 election: Election decided. Result: candidate won. Election summary: received 3 responses out of 3 voters: 2 yes votes; 1 no votes. yes voters: 3696b46ea81a4ad785cc7432f69fb1a0, 8c3643d88dd74ce28cd29681978e235e; no voters: dda6bfc86784449db64dd2dbf644ece4
I20260812 08:03:35.794221 27723 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 2 FOLLOWER]: Leader election won for term 2
I20260812 08:03:35.794399 27723 raft_consensus.cc:697] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 2 LEADER]: Becoming Leader. State: Replica: 3696b46ea81a4ad785cc7432f69fb1a0, State: Running, Role: LEADER
I20260812 08:03:35.794677 27723 consensus_queue.cc:237] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 7, Committed index: 7, Last appended: 1.7, Last appended by leader: 7, Current term: 2, Majority size: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:35.796288 27731 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 3696b46ea81a4ad785cc7432f69fb1a0. Latest consensus state: current_term: 2 leader_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:35.796409 27731 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:35.796727 27736 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 08:03:35.798175 27736 catalog_manager.cc:679] Loaded metadata for table test [id=2660fb430cdc45898b90cf762082f54c]
I20260812 08:03:35.799093 27736 tablet_loader.cc:96] loaded metadata for tablet 9ec4601b6b43479189d08680a0d27666 (table test [id=2660fb430cdc45898b90cf762082f54c])
I20260812 08:03:35.799216 27736 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 08:03:35.799615 27736 catalog_manager.cc:1295] Loaded cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:35.799669 27736 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 08:03:35.800168 27736 catalog_manager.cc:1540] Loading token signing keys...
I20260812 08:03:35.800545 27736 catalog_manager.cc:6103] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0: Loaded TSK: 0
I20260812 08:03:35.801060 27736 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 08:03:35.837913 26253 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
/tmp/dist-test-taskTZ4tza/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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.0.0.1:0
--local_ip_for_outbound_sockets=127.0.0.1
--webserver_interface=127.0.0.1
--webserver_port=0
--tserver_master_addrs=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911
--builtin_ntp_servers=127.0.0.1:44777
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
W20260812 08:03:35.938589 27738 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 08:03:35.938761 27738 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 08:03:35.938803 27738 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 08:03:35.941967 27738 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 08:03:35.942058 27738 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.0.1
I20260812 08:03:35.945705 27738 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:44777
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.0.0.1:0
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.0.1
--webserver_port=0
--tserver_master_addrs=127.25.163.126:44541,127.25.163.125:35049,127.25.163.124:41911
--never_fsync=true
--heap_profile_path=/tmp/kudu.27738
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.0.0.1

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 08:03:35.946666 27738 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 08:03:35.947832 27738 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 08:03:35.953931 27748 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 08:03:35.953928 27750 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 08:03:35.953938 27752 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 08:03:35.954149 27738 server_base.cc:1061] running on GCE node
I20260812 08:03:35.954506 27738 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 08:03:35.954990 27738 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 08:03:35.956166 27738 hybrid_clock.cc:648] HybridClock initialized: now 1786521815956150 us; error 26 us; skew 500 ppm
I20260812 08:03:35.957783 27738 webserver.cc:533] Webserver started at http://127.0.0.1:35223/ using document root <none> and password file <none>
I20260812 08:03:35.958271 27738 fs_manager.cc:362] Metadata directory not provided
I20260812 08:03:35.958328 27738 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 08:03:35.961328 27738 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 08:03:35.963001 27761 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 08:03:35.963923 27738 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 08:03:35.964010 27738 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data,/tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
uuid: "1adf4994a3104b799f8ba820093099ba"
format_stamp: "Formatted at 2026-08-12 08:03:32 on dist-test-slave-2j7r"
I20260812 08:03:35.964337 27738 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-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 08:03:35.975574 27738 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 08:03:35.976050 27738 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 08:03:35.976182 27738 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 08:03:35.976635 27738 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 08:03:35.977766 27772 ts_tablet_manager.cc:542] Loading tablet metadata (0/1 complete)
I20260812 08:03:35.980674 27738 ts_tablet_manager.cc:585] Loaded tablet metadata (1 total tablets, 1 live tablets)
I20260812 08:03:35.980738 27738 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.003s	user 0.000s	sys 0.000s
I20260812 08:03:35.980800 27738 ts_tablet_manager.cc:600] Registering tablets (0/1 complete)
I20260812 08:03:35.982218 27738 ts_tablet_manager.cc:616] Registered 1 tablets
I20260812 08:03:35.982296 27738 ts_tablet_manager.cc:595] Time spent register tablets: real 0.002s	user 0.000s	sys 0.000s
I20260812 08:03:35.982566 27772 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Bootstrap starting.
I20260812 08:03:35.992125 27738 rpc_server.cc:307] RPC server started. Bound to: 127.0.0.1:36597
I20260812 08:03:35.992194 27973 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.0.1:36597 every 8 connection(s)
I20260812 08:03:35.993057 27738 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/data/info.pb
I20260812 08:03:35.993791 26253 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu as pid 27738
I20260812 08:03:35.993914 26253 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskTZ4tza/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786521811977880-26253-0/minicluster-data/ts-0/wal/instance
I20260812 08:03:36.000070 27772 log.cc:826] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Log is configured to *not* fsync() on all Append() calls
I20260812 08:03:36.003106 27772 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Bootstrap replayed 1/1 log segments. Stats: ops{read=2 overwritten=0 applied=2 ignored=0} inserts{seen=0 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 08:03:36.003136 27978 heartbeater.cc:344] Connected to a master server at 127.25.163.126:44541
I20260812 08:03:36.003345 27978 heartbeater.cc:461] Registering TS with master...
I20260812 08:03:36.003477 27772 tablet_bootstrap.cc:492] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Bootstrap complete.
I20260812 08:03:36.003896 27978 heartbeater.cc:507] Master 127.25.163.126:44541 requested a full tablet report, sending...
I20260812 08:03:36.004546 27772 ts_tablet_manager.cc:1403] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Time spent bootstrapping tablet: real 0.022s	user 0.003s	sys 0.015s
I20260812 08:03:36.005693 27979 heartbeater.cc:344] Connected to a master server at 127.25.163.125:35049
I20260812 08:03:36.005782 27979 heartbeater.cc:461] Registering TS with master...
I20260812 08:03:36.005982 27979 heartbeater.cc:507] Master 127.25.163.125:35049 requested a full tablet report, sending...
I20260812 08:03:36.006240 27285 ts_manager.cc:194] Registered new tserver with Master: 1adf4994a3104b799f8ba820093099ba (127.0.0.1:36597)
I20260812 08:03:36.006290 27977 heartbeater.cc:344] Connected to a master server at 127.25.163.124:41911
I20260812 08:03:36.006354 27977 heartbeater.cc:461] Registering TS with master...
I20260812 08:03:36.006525 27977 heartbeater.cc:507] Master 127.25.163.124:41911 requested a full tablet report, sending...
I20260812 08:03:36.007385 27772 raft_consensus.cc:359] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:36.007722 27460 ts_manager.cc:194] Registered new tserver with Master: 1adf4994a3104b799f8ba820093099ba (127.0.0.1:36597)
I20260812 08:03:36.007755 27772 raft_consensus.cc:740] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Initialized, Role: FOLLOWER
I20260812 08:03:36.008174 27772 consensus_queue.cc:260] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 2, Last appended: 2.2, Last appended by leader: 2, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:36.008311 27772 raft_consensus.cc:399] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:36.008369 27772 raft_consensus.cc:493] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:36.008473 27772 raft_consensus.cc:3060] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 2 FOLLOWER]: Advancing to term 3
I20260812 08:03:36.010146 27627 ts_manager.cc:194] Registered new tserver with Master: 1adf4994a3104b799f8ba820093099ba (127.0.0.1:36597)
I20260812 08:03:36.010257 27460 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:50187
I20260812 08:03:36.010269 27772 raft_consensus.cc:515] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 3 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:36.010597 27772 leader_election.cc:304] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 3 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: 1adf4994a3104b799f8ba820093099ba; no voters: 
I20260812 08:03:36.010905 27772 leader_election.cc:290] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 3 election: Requested vote from peers 
I20260812 08:03:36.010975 27994 raft_consensus.cc:2804] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 3 FOLLOWER]: Leader election won for term 3
I20260812 08:03:36.011219 27994 raft_consensus.cc:697] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [term 3 LEADER]: Becoming Leader. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Running, Role: LEADER
I20260812 08:03:36.011716 27772 ts_tablet_manager.cc:1434] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba: Time spent starting tablet: real 0.007s	user 0.007s	sys 0.002s
I20260812 08:03:36.012146 27979 heartbeater.cc:499] Master 127.25.163.125:35049 was elected leader, sending a full tablet report...
I20260812 08:03:36.011497 27994 consensus_queue.cc:237] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 2, Committed index: 2, Last appended: 2.2, Last appended by leader: 2, Current term: 3, Majority size: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } }
I20260812 08:03:36.014813 27460 catalog_manager.cc:5719] T 9ec4601b6b43479189d08680a0d27666 P 1adf4994a3104b799f8ba820093099ba reported cstate change: term changed from 1 to 3. New cstate: current_term: 3 leader_uuid: "1adf4994a3104b799f8ba820093099ba" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 38983 } health_report { overall_health: HEALTHY } } }
I20260812 08:03:36.015955 26253 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 08:03:36.018158 27648 raft_consensus.cc:3060] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 1 FOLLOWER]: Advancing to term 2
I20260812 08:03:36.018285 27306 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 2 FOLLOWER]: Refusing update from remote peer 3696b46ea81a4ad785cc7432f69fb1a0: Log matching property violated. Preceding OpId in replica: term: 1 index: 7. Preceding OpId from leader: term: 2 index: 9. (index mismatch)
I20260812 08:03:36.018867 27731 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [LEADER]: Connected to new peer: Peer: permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 8, Last known committed idx: 7, Time since last communication: 0.000s
I20260812 08:03:36.020361 27648 raft_consensus.cc:1275] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Refusing update from remote peer 3696b46ea81a4ad785cc7432f69fb1a0: Log matching property violated. Preceding OpId in replica: term: 1 index: 7. Preceding OpId from leader: term: 2 index: 9. (index mismatch)
I20260812 08:03:36.021550 27731 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [LEADER]: Connected to new peer: Peer: permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 8, Last known committed idx: 7, Time since last communication: 0.000s
I20260812 08:03:36.025400 28018 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 3696b46ea81a4ad785cc7432f69fb1a0. Latest consensus state: current_term: 2 leader_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:36.025520 28018 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:36.025660 28021 sys_catalog.cc:455] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 3696b46ea81a4ad785cc7432f69fb1a0. Latest consensus state: current_term: 2 leader_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:36.025775 28021 sys_catalog.cc:458] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:36.027153 28002 mvcc.cc:204] Tried to move back new op lower bound from 7317593358405042176 to 7317593357497479168. Current Snapshot: MvccSnapshot[applied={T|T < 7317593358405042176}]
I20260812 08:03:36.027418 27723 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 2 leader_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:36.027537 27723 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:36.027601 27731 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 2 leader_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:36.027701 27731 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:36.028350 28021 sys_catalog.cc:455] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 2 leader_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:36.028431 28018 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 2 leader_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:36.028448 28021 sys_catalog.cc:458] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:36.028535 28018 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:36.053838 27460 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:41446:
name: "second_table"
schema {
  columns {
    name: "key"
    type: INT32
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
    columns {
      name: "key"
    }
  }
}
I20260812 08:03:36.070152 27841 tablet_service.cc:1511] Processing CreateTablet for tablet 7b93eb256a4348e8933cb6d928e4ef70 (DEFAULT_TABLE table=second_table [id=a4f972baa99446b7a071e7e761082995]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 08:03:36.070705 27841 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 7b93eb256a4348e8933cb6d928e4ef70. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 08:03:36.073388 27772 tablet_bootstrap.cc:492] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba: Bootstrap starting.
I20260812 08:03:36.074307 27772 tablet_bootstrap.cc:654] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba: Neither blocks nor log segments found. Creating new log.
I20260812 08:03:36.075361 27772 tablet_bootstrap.cc:492] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba: No bootstrap required, opened a new log
I20260812 08:03:36.075428 27772 ts_tablet_manager.cc:1403] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.001s
I20260812 08:03:36.075917 27772 raft_consensus.cc:359] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.076009 27772 raft_consensus.cc:385] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 08:03:36.076036 27772 raft_consensus.cc:740] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Initialized, Role: FOLLOWER
I20260812 08:03:36.076160 27772 consensus_queue.cc:260] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.076227 27772 raft_consensus.cc:399] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:36.076249 27772 raft_consensus.cc:493] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:36.076295 27772 raft_consensus.cc:3060] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Advancing to term 1
I20260812 08:03:36.076910 27772 raft_consensus.cc:515] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.077066 27772 leader_election.cc:304] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [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: 1adf4994a3104b799f8ba820093099ba; no voters: 
I20260812 08:03:36.077203 27772 leader_election.cc:290] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 08:03:36.077283 27994 raft_consensus.cc:2804] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Leader election won for term 1
I20260812 08:03:36.077404 27994 raft_consensus.cc:697] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [term 1 LEADER]: Becoming Leader. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Running, Role: LEADER
I20260812 08:03:36.077430 27772 ts_tablet_manager.cc:1434] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba: Time spent starting tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 08:03:36.077524 27994 consensus_queue.cc:237] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.080241 27460 catalog_manager.cc:5719] T 7b93eb256a4348e8933cb6d928e4ef70 P 1adf4994a3104b799f8ba820093099ba reported cstate change: term changed from 0 to 1, leader changed from <none> to 1adf4994a3104b799f8ba820093099ba (127.0.0.1). New cstate: current_term: 1 leader_uuid: "1adf4994a3104b799f8ba820093099ba" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } health_report { overall_health: HEALTHY } } }
I20260812 08:03:36.110920 27460 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:41458:
name: "table-0"
schema {
  columns {
    name: "key"
    type: INT32
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
    columns {
      name: "key"
    }
  }
}
I20260812 08:03:36.118870 27841 tablet_service.cc:1511] Processing CreateTablet for tablet 261e054149b54164b2060e9d7fd5df4a (DEFAULT_TABLE table=table-0 [id=33a243e67186413ab105a7169be62a58]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 08:03:36.119210 27841 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 261e054149b54164b2060e9d7fd5df4a. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 08:03:36.121433 27772 tablet_bootstrap.cc:492] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba: Bootstrap starting.
I20260812 08:03:36.122340 27772 tablet_bootstrap.cc:654] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba: Neither blocks nor log segments found. Creating new log.
I20260812 08:03:36.123303 27772 tablet_bootstrap.cc:492] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba: No bootstrap required, opened a new log
I20260812 08:03:36.123397 27772 ts_tablet_manager.cc:1403] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.001s
I20260812 08:03:36.123870 27772 raft_consensus.cc:359] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.123997 27772 raft_consensus.cc:385] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 08:03:36.124032 27772 raft_consensus.cc:740] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Initialized, Role: FOLLOWER
I20260812 08:03:36.124147 27772 consensus_queue.cc:260] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.124245 27772 raft_consensus.cc:399] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:36.124289 27772 raft_consensus.cc:493] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:36.124337 27772 raft_consensus.cc:3060] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Advancing to term 1
I20260812 08:03:36.124980 27772 raft_consensus.cc:515] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.125128 27772 leader_election.cc:304] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [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: 1adf4994a3104b799f8ba820093099ba; no voters: 
I20260812 08:03:36.125229 27772 leader_election.cc:290] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 08:03:36.125288 27997 raft_consensus.cc:2804] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Leader election won for term 1
I20260812 08:03:36.125365 27997 raft_consensus.cc:697] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [term 1 LEADER]: Becoming Leader. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Running, Role: LEADER
I20260812 08:03:36.125535 27772 ts_tablet_manager.cc:1434] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba: Time spent starting tablet: real 0.002s	user 0.001s	sys 0.001s
I20260812 08:03:36.125485 27997 consensus_queue.cc:237] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:36.127714 27460 catalog_manager.cc:5719] T 261e054149b54164b2060e9d7fd5df4a P 1adf4994a3104b799f8ba820093099ba reported cstate change: term changed from 0 to 1, leader changed from <none> to 1adf4994a3104b799f8ba820093099ba (127.0.0.1). New cstate: current_term: 1 leader_uuid: "1adf4994a3104b799f8ba820093099ba" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } health_report { overall_health: HEALTHY } } }
I20260812 08:03:37.631536 28074 raft_consensus.cc:493] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Starting pre-election (detected failure of leader 3696b46ea81a4ad785cc7432f69fb1a0)
I20260812 08:03:37.631691 28074 raft_consensus.cc:515] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:37.632958 28074 leader_election.cc:290] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [CANDIDATE]: Term 3 pre-election: Requested pre-vote from peers 8c3643d88dd74ce28cd29681978e235e (127.25.163.126:44541), 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049)
I20260812 08:03:37.633540 27306 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "8c3643d88dd74ce28cd29681978e235e" is_pre_election: true
I20260812 08:03:37.637679 28075 raft_consensus.cc:493] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 2 FOLLOWER]: Starting pre-election (detected failure of leader 3696b46ea81a4ad785cc7432f69fb1a0)
I20260812 08:03:37.637789 28075 raft_consensus.cc:515] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 2 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:37.638808 28075 leader_election.cc:290] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 3 pre-election: Requested pre-vote from peers 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049), dda6bfc86784449db64dd2dbf644ece4 (127.25.163.124:41911)
I20260812 08:03:37.642683 27648 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8c3643d88dd74ce28cd29681978e235e" candidate_term: 3 candidate_status { last_received { term: 2 index: 13 } } ignore_live_leader: false dest_uuid: "dda6bfc86784449db64dd2dbf644ece4" is_pre_election: true
I20260812 08:03:37.642835 27648 raft_consensus.cc:2410] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Leader pre-election vote request: Denying vote to candidate 8c3643d88dd74ce28cd29681978e235e for term 3 because replica has last-logged OpId of term: 2 index: 15, which is greater than that of the candidate, which has last-logged OpId of term: 2 index: 13.
W20260812 08:03:39.318626 27598 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: RequestConsensusVote RPC to 127.25.163.125:35049 timed out after 1.684s (SENT)
W20260812 08:03:39.698892 27264 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: connection negotiation to 127.25.163.125:35049 for RPC RequestConsensusVote timed out after 2.059s (ON_OUTBOUND_QUEUE)
W20260812 08:03:40.725917 28077 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:37.639028 (+     0us) reactor.cc:730] Submitting negotiation task for client connection to 127.25.163.125:35049 (local address 127.0.0.1:57962)
0812 08:03:37.639275 (+   247us) negotiation.cc:107] Waiting for socket to connect
0812 08:03:37.639292 (+    17us) client_negotiation.cc:175] Beginning negotiation
0812 08:03:37.639410 (+   118us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 08:03:40.725401 (+3085991us) negotiation.cc:326] Negotiation complete: Timed out: Client connection negotiation failed: client connection to 127.25.163.125:35049: received 0 of 4 requested bytes
Metrics: {"client-negotiator.queue_time_us":158,"thread_start_us":73,"threads_started":1}
W20260812 08:03:40.726179 27264 leader_election.cc:336] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 3 pre-election: RPC error from VoteRequest() call to peer 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049): Timed out: Client connection negotiation failed: client connection to 127.25.163.125:35049: received 0 of 4 requested bytes
I20260812 08:03:40.726293 27264 leader_election.cc:304] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 3 pre-election: Election decided. Result: candidate lost. Election summary: received 3 responses out of 3 voters: 1 yes votes; 2 no votes. yes voters: 8c3643d88dd74ce28cd29681978e235e; no voters: 3696b46ea81a4ad785cc7432f69fb1a0, dda6bfc86784449db64dd2dbf644ece4
I20260812 08:03:40.726701 28082 raft_consensus.cc:2749] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 2 FOLLOWER]: Leader pre-election lost for term 3. Reason: could not achieve majority
I20260812 08:03:40.726794 27235 master_runner.cc:187] Error getting master registration for 127.25.163.125:35049: Timed out: Client connection negotiation failed: client connection to 127.25.163.125:35049: received 0 of 4 requested bytes, 
I20260812 08:03:40.726900 27235 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 08:03:41.004868 27598 leader_election.cc:336] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [CANDIDATE]: Term 3 pre-election: RPC error from VoteRequest() call to peer 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049): Timed out: RequestConsensusVote RPC to 127.25.163.125:35049 timed out after 1.684s (SENT)
I20260812 08:03:41.004985 27598 leader_election.cc:304] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [CANDIDATE]: Term 3 pre-election: Election decided. Result: candidate lost. Election summary: received 3 responses out of 3 voters: 1 yes votes; 2 no votes. yes voters: dda6bfc86784449db64dd2dbf644ece4; no voters: 3696b46ea81a4ad785cc7432f69fb1a0, 8c3643d88dd74ce28cd29681978e235e
I20260812 08:03:41.005445 28083 raft_consensus.cc:2749] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Leader pre-election lost for term 3. Reason: could not achieve majority
I20260812 08:03:42.572685 28085 raft_consensus.cc:493] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Starting pre-election (detected failure of leader 3696b46ea81a4ad785cc7432f69fb1a0)
I20260812 08:03:42.572841 28085 raft_consensus.cc:515] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:42.573408 28085 leader_election.cc:290] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [CANDIDATE]: Term 3 pre-election: Requested pre-vote from peers 8c3643d88dd74ce28cd29681978e235e (127.25.163.126:44541), 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049)
I20260812 08:03:42.573873 27306 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "8c3643d88dd74ce28cd29681978e235e" is_pre_election: true
I20260812 08:03:42.574021 27306 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 2 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate dda6bfc86784449db64dd2dbf644ece4 in term 2.
I20260812 08:03:42.574417 27593 leader_election.cc:304] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [CANDIDATE]: Term 3 pre-election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 8c3643d88dd74ce28cd29681978e235e, dda6bfc86784449db64dd2dbf644ece4; no voters: 
I20260812 08:03:42.574589 28085 raft_consensus.cc:2804] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Leader pre-election won for term 3
I20260812 08:03:42.574645 28085 raft_consensus.cc:493] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Starting leader election (detected failure of leader 3696b46ea81a4ad785cc7432f69fb1a0)
I20260812 08:03:42.574689 28085 raft_consensus.cc:3060] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 2 FOLLOWER]: Advancing to term 3
I20260812 08:03:42.575598 28085 raft_consensus.cc:515] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 3 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:42.575951 28085 leader_election.cc:290] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [CANDIDATE]: Term 3 election: Requested vote from peers 8c3643d88dd74ce28cd29681978e235e (127.25.163.126:44541), 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049)
I20260812 08:03:42.576318 27306 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "8c3643d88dd74ce28cd29681978e235e"
I20260812 08:03:42.576455 27306 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 2 FOLLOWER]: Advancing to term 3
I20260812 08:03:42.577243 27306 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 3 FOLLOWER]: Leader election vote request: Granting yes vote for candidate dda6bfc86784449db64dd2dbf644ece4 in term 3.
I20260812 08:03:42.577555 27593 leader_election.cc:304] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [CANDIDATE]: Term 3 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 8c3643d88dd74ce28cd29681978e235e, dda6bfc86784449db64dd2dbf644ece4; no voters: 
I20260812 08:03:42.577713 28085 raft_consensus.cc:2804] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 3 FOLLOWER]: Leader election won for term 3
I20260812 08:03:42.577872 28085 raft_consensus.cc:697] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 3 LEADER]: Becoming Leader. State: Replica: dda6bfc86784449db64dd2dbf644ece4, State: Running, Role: LEADER
I20260812 08:03:42.578158 28085 consensus_queue.cc:237] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 15, Committed index: 15, Last appended: 2.15, Last appended by leader: 15, Current term: 3, Majority size: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:42.579110 28088 sys_catalog.cc:455] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: SysCatalogTable state changed. Reason: New leader dda6bfc86784449db64dd2dbf644ece4. Latest consensus state: current_term: 3 leader_uuid: "dda6bfc86784449db64dd2dbf644ece4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:42.579237 28088 sys_catalog.cc:458] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:42.579527 28090 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 08:03:42.580979 28090 catalog_manager.cc:679] Loaded metadata for table test [id=2660fb430cdc45898b90cf762082f54c]
I20260812 08:03:42.581279 28090 catalog_manager.cc:679] Loaded metadata for table table-0 [id=33a243e67186413ab105a7169be62a58]
I20260812 08:03:42.581524 28090 catalog_manager.cc:679] Loaded metadata for table second_table [id=a4f972baa99446b7a071e7e761082995]
I20260812 08:03:42.582440 28090 tablet_loader.cc:96] loaded metadata for tablet 261e054149b54164b2060e9d7fd5df4a (table table-0 [id=33a243e67186413ab105a7169be62a58])
I20260812 08:03:42.582643 28090 tablet_loader.cc:96] loaded metadata for tablet 7b93eb256a4348e8933cb6d928e4ef70 (table second_table [id=a4f972baa99446b7a071e7e761082995])
I20260812 08:03:42.582808 28090 tablet_loader.cc:96] loaded metadata for tablet 9ec4601b6b43479189d08680a0d27666 (table test [id=2660fb430cdc45898b90cf762082f54c])
I20260812 08:03:42.582911 28090 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 08:03:42.583276 28090 catalog_manager.cc:1295] Loaded cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:42.583321 28090 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 08:03:42.583849 28090 catalog_manager.cc:1540] Loading token signing keys...
I20260812 08:03:42.584225 28090 catalog_manager.cc:6103] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4: Loaded TSK: 0
I20260812 08:03:42.584781 28090 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 08:03:43.135000 27306 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 3 FOLLOWER]: Refusing update from remote peer dda6bfc86784449db64dd2dbf644ece4: Log matching property violated. Preceding OpId in replica: term: 2 index: 13. Preceding OpId from leader: term: 3 index: 16. (index mismatch)
I20260812 08:03:43.135645 28088 consensus_queue.cc:1048] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [LEADER]: Connected to new peer: Peer: permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 16, Last known committed idx: 12, Time since last communication: 0.000s
I20260812 08:03:43.137951 28091 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: New leader dda6bfc86784449db64dd2dbf644ece4. Latest consensus state: current_term: 3 leader_uuid: "dda6bfc86784449db64dd2dbf644ece4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:43.138082 28091 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:43.139199 28099 sys_catalog.cc:455] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 3 leader_uuid: "dda6bfc86784449db64dd2dbf644ece4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:43.139313 28099 sys_catalog.cc:458] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:43.139436 28091 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 3 leader_uuid: "dda6bfc86784449db64dd2dbf644ece4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:43.139539 28091 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:44.142572 27977 heartbeater.cc:499] Master 127.25.163.124:41911 was elected leader, sending a full tablet report...
W20260812 08:03:44.850987 27598 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: RequestConsensusVote RPC to 127.25.163.125:35049 timed out after 2.274s (SENT)
W20260812 08:03:46.147750 28007 master_proxy_rpc.cc:203] Re-attempting GetTableSchema request to leader Master (127.25.163.125:35049)
I20260812 08:03:46.159945 27627 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:54352:
name: "table-1"
schema {
  columns {
    name: "key"
    type: INT32
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
    columns {
      name: "key"
    }
  }
}
I20260812 08:03:46.174229 27841 tablet_service.cc:1511] Processing CreateTablet for tablet 4a27d350ab4c458bae0bf88a50cf1007 (DEFAULT_TABLE table=table-1 [id=f625f2cd13c242ccb5b6e1561990784e]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 08:03:46.174521 27841 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 4a27d350ab4c458bae0bf88a50cf1007. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 08:03:46.176314 28151 tablet_bootstrap.cc:492] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba: Bootstrap starting.
I20260812 08:03:46.177060 28151 tablet_bootstrap.cc:654] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba: Neither blocks nor log segments found. Creating new log.
I20260812 08:03:46.178045 28151 tablet_bootstrap.cc:492] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba: No bootstrap required, opened a new log
I20260812 08:03:46.178136 28151 ts_tablet_manager.cc:1403] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
I20260812 08:03:46.178570 28151 raft_consensus.cc:359] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:46.178666 28151 raft_consensus.cc:385] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 08:03:46.178741 28151 raft_consensus.cc:740] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Initialized, Role: FOLLOWER
I20260812 08:03:46.178889 28151 consensus_queue.cc:260] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:46.178968 28151 raft_consensus.cc:399] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:46.179013 28151 raft_consensus.cc:493] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:46.179066 28151 raft_consensus.cc:3060] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Advancing to term 1
I20260812 08:03:46.179816 28151 raft_consensus.cc:515] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:46.179962 28151 leader_election.cc:304] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [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: 1adf4994a3104b799f8ba820093099ba; no voters: 
I20260812 08:03:46.180130 28151 leader_election.cc:290] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 08:03:46.180222 28153 raft_consensus.cc:2804] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Leader election won for term 1
I20260812 08:03:46.180414 28153 raft_consensus.cc:697] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [term 1 LEADER]: Becoming Leader. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Running, Role: LEADER
I20260812 08:03:46.180485 28151 ts_tablet_manager.cc:1434] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
I20260812 08:03:46.180625 28153 consensus_queue.cc:237] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:46.182008 27627 catalog_manager.cc:5719] T 4a27d350ab4c458bae0bf88a50cf1007 P 1adf4994a3104b799f8ba820093099ba reported cstate change: term changed from 0 to 1, leader changed from <none> to 1adf4994a3104b799f8ba820093099ba (127.0.0.1). New cstate: current_term: 1 leader_uuid: "1adf4994a3104b799f8ba820093099ba" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } health_report { overall_health: HEALTHY } } }
I20260812 08:03:46.188913 27470 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" is_pre_election: true
I20260812 08:03:46.189162 27468 raft_consensus.cc:3055] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 2 LEADER]: Stepping down as leader of term 2
I20260812 08:03:46.189237 27468 raft_consensus.cc:740] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 2 LEADER]: Becoming Follower/Learner. State: Replica: 3696b46ea81a4ad785cc7432f69fb1a0, State: Running, Role: LEADER
I20260812 08:03:46.189412 27468 consensus_queue.cc:260] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 15, Committed index: 15, Last appended: 2.15, Last appended by leader: 15, Current term: 2, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:46.189513 27469 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0"
I20260812 08:03:46.189700 27468 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 2 FOLLOWER]: Advancing to term 3
I20260812 08:03:46.189771 27472 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" is_pre_election: true
I20260812 08:03:46.189963 27475 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" is_pre_election: true
I20260812 08:03:46.190713 27468 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 3 FOLLOWER]: Refusing update from remote peer dda6bfc86784449db64dd2dbf644ece4: Log matching property violated. Preceding OpId in replica: term: 2 index: 15. Preceding OpId from leader: term: 3 index: 16. (index mismatch)
I20260812 08:03:46.191051 27467 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0"
I20260812 08:03:46.191948 27467 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 3 FOLLOWER]: Leader election vote request: Granting yes vote for candidate dda6bfc86784449db64dd2dbf644ece4 in term 3.
I20260812 08:03:46.192224 27466 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "dda6bfc86784449db64dd2dbf644ece4" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" is_pre_election: true
I20260812 08:03:46.192346 27466 raft_consensus.cc:2376] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 3 FOLLOWER]: Leader pre-election vote request: Already granted yes vote for candidate dda6bfc86784449db64dd2dbf644ece4 in term 3. Re-sending same reply.
W20260812 08:03:46.195367 28162 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:46.193716 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:57962 (local address 127.25.163.125:35049)
0812 08:03:46.194010 (+   294us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:46.194014 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:46.194026 (+    12us) server_negotiation.cc:408] Connection header received
0812 08:03:46.194064 (+    38us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 08:03:46.194067 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 08:03:46.194105 (+    38us) server_negotiation.cc:378] Sending NEGOTIATE NegotiatePB response
0812 08:03:46.194635 (+   530us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:57962: BlockingWrite error: write error: Broken pipe (error 32)
Metrics: {"server-negotiator.queue_time_us":226,"thread_start_us":51,"threads_started":1}
I20260812 08:03:47.685971 28164 raft_consensus.cc:493] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 3 FOLLOWER]: Starting pre-election (detected failure of leader dda6bfc86784449db64dd2dbf644ece4)
I20260812 08:03:47.686131 28164 raft_consensus.cc:515] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 3 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:47.686643 28164 leader_election.cc:290] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 4 pre-election: Requested pre-vote from peers 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049), dda6bfc86784449db64dd2dbf644ece4 (127.25.163.124:41911)
I20260812 08:03:47.687181 27466 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8c3643d88dd74ce28cd29681978e235e" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" is_pre_election: true
I20260812 08:03:47.687340 27466 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 3 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 8c3643d88dd74ce28cd29681978e235e in term 3.
I20260812 08:03:47.687727 27264 leader_election.cc:304] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 4 pre-election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 3696b46ea81a4ad785cc7432f69fb1a0, 8c3643d88dd74ce28cd29681978e235e; no voters: 
I20260812 08:03:47.687961 28164 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 3 FOLLOWER]: Leader pre-election won for term 4
I20260812 08:03:47.688033 28164 raft_consensus.cc:493] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 3 FOLLOWER]: Starting leader election (detected failure of leader dda6bfc86784449db64dd2dbf644ece4)
I20260812 08:03:47.688081 28164 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 3 FOLLOWER]: Advancing to term 4
I20260812 08:03:47.689030 28164 raft_consensus.cc:515] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 4 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:47.689414 28164 leader_election.cc:290] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 4 election: Requested vote from peers 3696b46ea81a4ad785cc7432f69fb1a0 (127.25.163.125:35049), dda6bfc86784449db64dd2dbf644ece4 (127.25.163.124:41911)
I20260812 08:03:47.689826 27466 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8c3643d88dd74ce28cd29681978e235e" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "3696b46ea81a4ad785cc7432f69fb1a0"
I20260812 08:03:47.689942 27466 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 3 FOLLOWER]: Advancing to term 4
I20260812 08:03:47.690884 27466 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 4 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 8c3643d88dd74ce28cd29681978e235e in term 4.
I20260812 08:03:47.691183 27264 leader_election.cc:304] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 4 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 3696b46ea81a4ad785cc7432f69fb1a0, 8c3643d88dd74ce28cd29681978e235e; no voters: 
I20260812 08:03:47.691406 28164 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 4 FOLLOWER]: Leader election won for term 4
I20260812 08:03:47.691614 28164 raft_consensus.cc:697] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [term 4 LEADER]: Becoming Leader. State: Replica: 8c3643d88dd74ce28cd29681978e235e, State: Running, Role: LEADER
I20260812 08:03:47.691967 28164 consensus_queue.cc:237] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 19, Committed index: 19, Last appended: 3.19, Last appended by leader: 19, Current term: 4, Majority size: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:47.693043 28165 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8c3643d88dd74ce28cd29681978e235e. Latest consensus state: current_term: 4 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:47.693174 28165 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:47.693492 28173 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 08:03:47.695101 28173 catalog_manager.cc:679] Loaded metadata for table test [id=2660fb430cdc45898b90cf762082f54c]
I20260812 08:03:47.695446 28173 catalog_manager.cc:679] Loaded metadata for table table-0 [id=33a243e67186413ab105a7169be62a58]
I20260812 08:03:47.695668 28173 catalog_manager.cc:679] Loaded metadata for table second_table [id=a4f972baa99446b7a071e7e761082995]
I20260812 08:03:47.695880 28173 catalog_manager.cc:679] Loaded metadata for table table-1 [id=f625f2cd13c242ccb5b6e1561990784e]
I20260812 08:03:47.696880 28173 tablet_loader.cc:96] loaded metadata for tablet 261e054149b54164b2060e9d7fd5df4a (table table-0 [id=33a243e67186413ab105a7169be62a58])
I20260812 08:03:47.697134 28173 tablet_loader.cc:96] loaded metadata for tablet 4a27d350ab4c458bae0bf88a50cf1007 (table table-1 [id=f625f2cd13c242ccb5b6e1561990784e])
I20260812 08:03:47.697345 28173 tablet_loader.cc:96] loaded metadata for tablet 7b93eb256a4348e8933cb6d928e4ef70 (table second_table [id=a4f972baa99446b7a071e7e761082995])
I20260812 08:03:47.697521 28173 tablet_loader.cc:96] loaded metadata for tablet 9ec4601b6b43479189d08680a0d27666 (table test [id=2660fb430cdc45898b90cf762082f54c])
I20260812 08:03:47.697631 28173 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 08:03:47.698084 28173 catalog_manager.cc:1295] Loaded cluster ID: 784cb45b560f4bf1a8404d46ba37e6ed
I20260812 08:03:47.698134 28173 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 08:03:47.698745 28173 catalog_manager.cc:1540] Loading token signing keys...
I20260812 08:03:47.699185 28173 catalog_manager.cc:6103] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e: Loaded TSK: 0
I20260812 08:03:47.699800 28173 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 08:03:48.102517 27466 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [term 4 FOLLOWER]: Refusing update from remote peer 8c3643d88dd74ce28cd29681978e235e: Log matching property violated. Preceding OpId in replica: term: 2 index: 15. Preceding OpId from leader: term: 4 index: 20. (index mismatch)
I20260812 08:03:48.103106 28165 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [LEADER]: Connected to new peer: Peer: permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 20, Last known committed idx: 15, Time since last communication: 0.000s
I20260812 08:03:48.106042 28179 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 4 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:48.106211 28179 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:48.106262 28175 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8c3643d88dd74ce28cd29681978e235e. Latest consensus state: current_term: 4 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:48.107002 28164 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 4 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:48.107069 28175 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:48.107126 28164 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [sys.catalog]: This master's current role is: LEADER
I20260812 08:03:48.107291 28175 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 4 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:48.107406 28175 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3696b46ea81a4ad785cc7432f69fb1a0 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:49.187501 27978 heartbeater.cc:499] Master 127.25.163.126:44541 was elected leader, sending a full tablet report...
W20260812 08:03:49.445128 27264 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: RequestConsensusVote RPC to 127.25.163.124:41911 timed out after 1.757s (SENT)
W20260812 08:03:51.204133 27264 leader_election.cc:336] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 4 pre-election: RPC error from VoteRequest() call to peer dda6bfc86784449db64dd2dbf644ece4 (127.25.163.124:41911): Timed out: RequestConsensusVote RPC to 127.25.163.124:41911 timed out after 1.757s (SENT)
W20260812 08:03:51.349918 27264 leader_election.cc:336] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [CANDIDATE]: Term 4 election: RPC error from VoteRequest() call to peer dda6bfc86784449db64dd2dbf644ece4 (127.25.163.124:41911): Timed out: RequestConsensusVote RPC to 127.25.163.124:41911 timed out after 1.829s (SENT)
W20260812 08:03:56.200127 28007 master_proxy_rpc.cc:203] Re-attempting GetTableSchema request to leader Master (127.25.163.124:41911)
I20260812 08:03:56.214035 27279 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:44774:
name: "table-2"
schema {
  columns {
    name: "key"
    type: INT32
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
    columns {
      name: "key"
    }
  }
}
I20260812 08:03:56.228067 27841 tablet_service.cc:1511] Processing CreateTablet for tablet e87d2aab541244c2816d88bc2a20cc35 (DEFAULT_TABLE table=table-2 [id=2fb19a04fda940d49e0197c8e2979b28]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 08:03:56.228354 27841 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet e87d2aab541244c2816d88bc2a20cc35. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 08:03:56.230123 28223 tablet_bootstrap.cc:492] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba: Bootstrap starting.
I20260812 08:03:56.231117 28223 tablet_bootstrap.cc:654] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba: Neither blocks nor log segments found. Creating new log.
I20260812 08:03:56.232158 28223 tablet_bootstrap.cc:492] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba: No bootstrap required, opened a new log
I20260812 08:03:56.232280 28223 ts_tablet_manager.cc:1403] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
I20260812 08:03:56.232667 28223 raft_consensus.cc:359] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:56.232767 28223 raft_consensus.cc:385] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 08:03:56.232815 28223 raft_consensus.cc:740] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Initialized, Role: FOLLOWER
I20260812 08:03:56.232963 28223 consensus_queue.cc:260] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:56.233050 28223 raft_consensus.cc:399] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 08:03:56.233115 28223 raft_consensus.cc:493] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 08:03:56.233171 28223 raft_consensus.cc:3060] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 0 FOLLOWER]: Advancing to term 1
I20260812 08:03:56.233946 28223 raft_consensus.cc:515] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:56.234097 28223 leader_election.cc:304] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [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: 1adf4994a3104b799f8ba820093099ba; no voters: 
I20260812 08:03:56.234272 28223 leader_election.cc:290] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 08:03:56.234354 28228 raft_consensus.cc:2804] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 1 FOLLOWER]: Leader election won for term 1
I20260812 08:03:56.234573 28228 raft_consensus.cc:697] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [term 1 LEADER]: Becoming Leader. State: Replica: 1adf4994a3104b799f8ba820093099ba, State: Running, Role: LEADER
I20260812 08:03:56.234624 28223 ts_tablet_manager.cc:1434] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba: Time spent starting tablet: real 0.002s	user 0.000s	sys 0.003s
I20260812 08:03:56.234769 28228 consensus_queue.cc:237] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba [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: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } }
I20260812 08:03:56.236392 27285 catalog_manager.cc:5719] T e87d2aab541244c2816d88bc2a20cc35 P 1adf4994a3104b799f8ba820093099ba reported cstate change: term changed from 0 to 1, leader changed from <none> to 1adf4994a3104b799f8ba820093099ba (127.0.0.1). New cstate: current_term: 1 leader_uuid: "1adf4994a3104b799f8ba820093099ba" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1adf4994a3104b799f8ba820093099ba" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 36597 } health_report { overall_health: HEALTHY } } }
I20260812 08:03:56.242024 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 27738
W20260812 08:03:56.243508 27598 connection.cc:256] RPC call timeout handler was delayed by -9.3452584841288626s: this may be due to a process-wide pause such as swapping, logging-related delays, or allocator lock contention. Will allow extra 0.22740000000000002s for a response
W20260812 08:03:56.243609 27598 connection.cc:256] RPC call timeout handler was delayed by -7.98521869815886s: this may be due to a process-wide pause such as swapping, logging-related delays, or allocator lock contention. Will allow extra 0.29910000000000003s for a response
I20260812 08:03:56.252063 27643 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8c3643d88dd74ce28cd29681978e235e" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "dda6bfc86784449db64dd2dbf644ece4"
I20260812 08:03:56.252373 27645 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8c3643d88dd74ce28cd29681978e235e" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "dda6bfc86784449db64dd2dbf644ece4" is_pre_election: true
I20260812 08:03:56.252640 27646 raft_consensus.cc:3055] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 3 LEADER]: Stepping down as leader of term 3
I20260812 08:03:56.252681 27646 raft_consensus.cc:740] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 3 LEADER]: Becoming Follower/Learner. State: Replica: dda6bfc86784449db64dd2dbf644ece4, State: Running, Role: LEADER
I20260812 08:03:56.252818 27646 consensus_queue.cc:260] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 19, Committed index: 19, Last appended: 3.19, Last appended by leader: 19, Current term: 3, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } }
I20260812 08:03:56.252982 27647 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8c3643d88dd74ce28cd29681978e235e" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "dda6bfc86784449db64dd2dbf644ece4"
I20260812 08:03:56.255930 28112 consensus_queue.cc:1048] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [NON_LEADER]: Connected to new peer: Peer: permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 16, Last known committed idx: 15, Time since last communication: 0.000s
I20260812 08:03:56.256254 27648 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "8c3643d88dd74ce28cd29681978e235e" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "dda6bfc86784449db64dd2dbf644ece4" is_pre_election: true
I20260812 08:03:56.257311 27646 raft_consensus.cc:3060] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 3 FOLLOWER]: Advancing to term 4
I20260812 08:03:56.258414 27646 raft_consensus.cc:1275] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [term 4 FOLLOWER]: Refusing update from remote peer 8c3643d88dd74ce28cd29681978e235e: Log matching property violated. Preceding OpId in replica: term: 3 index: 19. Preceding OpId from leader: term: 4 index: 20. (index mismatch)
I20260812 08:03:56.259006 28214 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 8c3643d88dd74ce28cd29681978e235e [LEADER]: Connected to new peer: Peer: permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 20, Last known committed idx: 19, Time since last communication: 0.000s
I20260812 08:03:56.261675 27527 master_runner.cc:233] Existing masters have differing terms: 2 vs 4
I20260812 08:03:56.261659 28112 sys_catalog.cc:455] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8c3643d88dd74ce28cd29681978e235e. Latest consensus state: current_term: 4 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:56.261758 28112 sys_catalog.cc:458] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:56.261783 27527 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
I20260812 08:03:56.261847 28235 sys_catalog.cc:455] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 4 leader_uuid: "8c3643d88dd74ce28cd29681978e235e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8c3643d88dd74ce28cd29681978e235e" member_type: VOTER last_known_addr { host: "127.25.163.126" port: 44541 } } peers { permanent_uuid: "3696b46ea81a4ad785cc7432f69fb1a0" member_type: VOTER last_known_addr { host: "127.25.163.125" port: 35049 } } peers { permanent_uuid: "dda6bfc86784449db64dd2dbf644ece4" member_type: VOTER last_known_addr { host: "127.25.163.124" port: 41911 } } }
I20260812 08:03:56.261921 28235 sys_catalog.cc:458] T 00000000000000000000000000000000 P dda6bfc86784449db64dd2dbf644ece4 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 08:03:56.262804 28237 mvcc.cc:204] Tried to move back new op lower bound from 7317593441228218368 to 7317593406228844544. Current Snapshot: MvccSnapshot[applied={T|T < 7317593441228218368}]
W20260812 08:03:56.266187 27598 connection.cc:573] server connection from 127.0.0.1:39113 recv error: Network error: recv error from unknown peer: Transport endpoint is not connected (error 107)
I20260812 08:03:56.266738 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 27235
I20260812 08:03:56.280790 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 27364
I20260812 08:03:56.292728 26253 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskTZ4tza/build/debug/bin/kudu with pid 27527
2026-08-12T08:03:56Z chronyd exiting
[       OK ] MasterMigrationTest.TestEndToEndMigration (24333 ms)
[----------] 1 test from MasterMigrationTest (24333 ms total)

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