[==========] Running 1 test from 1 test suite.
[----------] Global test environment set-up.
[----------] 1 test from MasterMigrationTest
[ RUN      ] MasterMigrationTest.TestEndToEndMigration
2026-08-12T06:37:13Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T06:37:13Z Disabled control of system clock
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:37:13.641592 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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.200.254:37995
--webserver_interface=127.25.200.254
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:45961
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.200.254:37995 with env {}
W20260812 06:37:13.759395 26418 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:13.759630 26418 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:13.759687 26418 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:13.763051 26418 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:13.763106 26418 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:13.763127 26418 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:13.763144 26418 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:13.767057 26418 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:45961
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.25.200.254:37995
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.200.254:37995
--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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.25.200.254
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.26418
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:13.768285 26418 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:13.769639 26418 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:13.775621 26425 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:13.775573 26426 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:13.775570 26428 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:13.775920 26418 server_base.cc:1061] running on GCE node
I20260812 06:37:13.776485 26418 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:13.777407 26418 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:13.778591 26418 hybrid_clock.cc:648] HybridClock initialized: now 1786516633778597 us; error 55 us; skew 500 ppm
I20260812 06:37:13.780382 26418 webserver.cc:533] Webserver started at http://127.25.200.254:45989/ using document root <none> and password file <none>
I20260812 06:37:13.781042 26418 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:13.781097 26418 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:13.781302 26418 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:13.782815 26418 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/instance:
uuid: "bfb892b2fa954f8ea24cbca371deef08"
format_stamp: "Formatted at 2026-08-12 06:37:13 on dist-test-slave-zh2d"
I20260812 06:37:13.783289 26418 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal/instance:
uuid: "bfb892b2fa954f8ea24cbca371deef08"
format_stamp: "Formatted at 2026-08-12 06:37:13 on dist-test-slave-zh2d"
I20260812 06:37:13.786685 26418 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
I20260812 06:37:13.788808 26436 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:13.789856 26418 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:13.789981 26418 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
uuid: "bfb892b2fa954f8ea24cbca371deef08"
format_stamp: "Formatted at 2026-08-12 06:37:13 on dist-test-slave-zh2d"
I20260812 06:37:13.790078 26418 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:13.827919 26418 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:13.828718 26418 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:13.828953 26418 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:13.837554 26514 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.200.254:37995 every 8 connection(s)
I20260812 06:37:13.837564 26418 rpc_server.cc:307] RPC server started. Bound to: 127.25.200.254:37995
I20260812 06:37:13.838670 26418 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:13.841741 26515 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:13.846876 26515 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Bootstrap starting.
I20260812 06:37:13.847584 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 26418
I20260812 06:37:13.847731 26403 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal/instance
I20260812 06:37:13.849370 26515 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:13.850214 26515 log.cc:826] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:13.852231 26515 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: No bootstrap required, opened a new log
I20260812 06:37:13.855340 26515 raft_consensus.cc:359] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } }
I20260812 06:37:13.855578 26515 raft_consensus.cc:385] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:13.855640 26515 raft_consensus.cc:740] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: bfb892b2fa954f8ea24cbca371deef08, State: Initialized, Role: FOLLOWER
I20260812 06:37:13.856073 26515 consensus_queue.cc:260] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } }
I20260812 06:37:13.856192 26515 raft_consensus.cc:399] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:13.856230 26515 raft_consensus.cc:493] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:13.856348 26515 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:13.857288 26515 raft_consensus.cc:515] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } }
I20260812 06:37:13.857649 26515 leader_election.cc:304] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: bfb892b2fa954f8ea24cbca371deef08; no voters: 
I20260812 06:37:13.858016 26515 leader_election.cc:290] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:13.858155 26528 raft_consensus.cc:2804] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:13.858439 26528 raft_consensus.cc:697] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 LEADER]: Becoming Leader. State: Replica: bfb892b2fa954f8ea24cbca371deef08, State: Running, Role: LEADER
I20260812 06:37:13.858750 26528 consensus_queue.cc:237] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } }
I20260812 06:37:13.859238 26515 sys_catalog.cc:565] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:13.860757 26530 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: New leader bfb892b2fa954f8ea24cbca371deef08. Latest consensus state: current_term: 1 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } }
I20260812 06:37:13.860803 26529 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } }
I20260812 06:37:13.860915 26530 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:13.860957 26529 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:13.862357 26535 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:13.865229 26535 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:13.871050 26535 catalog_manager.cc:1383] Generated new cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:13.871114 26535 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:13.883999 26535 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:37:13.885190 26535 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:13.893659 26535 catalog_manager.cc:6092] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Generated new TSK 0
I20260812 06:37:13.894399 26535 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:13.899546 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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.200.254:37995
--builtin_ntp_servers=127.0.0.1:45961
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
W20260812 06:37:14.011221 26559 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:14.011482 26559 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:14.011564 26559 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:14.014931 26559 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:14.015127 26559 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.0.1
I20260812 06:37:14.018953 26559 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:45961
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.0.1
--webserver_port=0
--tserver_master_addrs=127.25.200.254:37995
--never_fsync=true
--heap_profile_path=/tmp/kudu.26559
--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 06:37:14.020099 26559 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:14.021463 26559 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:14.028374 26575 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:14.028579 26559 server_base.cc:1061] running on GCE node
W20260812 06:37:14.028672 26581 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:14.028409 26576 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:14.029114 26559 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:14.029799 26559 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:14.030966 26559 hybrid_clock.cc:648] HybridClock initialized: now 1786516634030956 us; error 27 us; skew 500 ppm
I20260812 06:37:14.033051 26559 webserver.cc:533] Webserver started at http://127.0.0.1:44673/ using document root <none> and password file <none>
I20260812 06:37:14.033672 26559 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:14.033728 26559 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:14.033941 26559 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:14.035570 26559 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/instance:
uuid: "fdc3a862e93348f494337b3c43cc4cb4"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:14.036072 26559 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal/instance:
uuid: "fdc3a862e93348f494337b3c43cc4cb4"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:14.039574 26559 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.001s	sys 0.004s
I20260812 06:37:14.041819 26588 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:14.042845 26559 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:14.042973 26559 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
uuid: "fdc3a862e93348f494337b3c43cc4cb4"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:14.043076 26559 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:14.061779 26559 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:14.062543 26559 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:14.062733 26559 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:14.063328 26559 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:14.064391 26559 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:14.064463 26559 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:14.064541 26559 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:14.064592 26559 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:14.074946 26559 rpc_server.cc:307] RPC server started. Bound to: 127.0.0.1:46085
I20260812 06:37:14.074991 26786 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.0.1:46085 every 8 connection(s)
I20260812 06:37:14.076015 26559 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:14.076208 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 26559
I20260812 06:37:14.076364 26403 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal/instance
I20260812 06:37:14.084332 26788 heartbeater.cc:344] Connected to a master server at 127.25.200.254:37995
I20260812 06:37:14.084575 26788 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:14.085135 26788 heartbeater.cc:507] Master 127.25.200.254:37995 requested a full tablet report, sending...
I20260812 06:37:14.086680 26465 ts_manager.cc:194] Registered new tserver with Master: fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1:46085)
I20260812 06:37:14.087891 26465 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:32917
I20260812 06:37:14.091162 26403 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 06:37:14.102548 26465 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:55370:
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 06:37:14.119634 26673 tablet_service.cc:1511] Processing CreateTablet for tablet 87f8e3e6f5844329a455cce463bfdcde (DEFAULT_TABLE table=test [id=cccdb7513d80485390c14b18d69dc055]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:14.120716 26673 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 87f8e3e6f5844329a455cce463bfdcde. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:14.125813 26803 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap starting.
I20260812 06:37:14.127956 26803 tablet_bootstrap.cc:654] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:14.128757 26803 log.cc:826] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:14.130587 26803 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: No bootstrap required, opened a new log
I20260812 06:37:14.130798 26803 ts_tablet_manager.cc:1403] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Time spent bootstrapping tablet: real 0.005s	user 0.000s	sys 0.004s
I20260812 06:37:14.133205 26803 raft_consensus.cc:359] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:14.133399 26803 raft_consensus.cc:385] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:14.133481 26803 raft_consensus.cc:740] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Initialized, Role: FOLLOWER
I20260812 06:37:14.133878 26803 consensus_queue.cc:260] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:14.134022 26803 raft_consensus.cc:399] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:14.134107 26803 raft_consensus.cc:493] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:14.134233 26803 raft_consensus.cc:3060] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:14.135322 26803 raft_consensus.cc:515] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:14.135658 26803 leader_election.cc:304] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: fdc3a862e93348f494337b3c43cc4cb4; no voters: 
I20260812 06:37:14.136005 26803 leader_election.cc:290] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:14.136139 26805 raft_consensus.cc:2804] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:14.136417 26805 raft_consensus.cc:697] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 LEADER]: Becoming Leader. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Running, Role: LEADER
I20260812 06:37:14.136745 26805 consensus_queue.cc:237] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:14.137034 26803 ts_tablet_manager.cc:1434] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Time spent starting tablet: real 0.006s	user 0.006s	sys 0.002s
I20260812 06:37:14.137264 26788 heartbeater.cc:499] Master 127.25.200.254:37995 was elected leader, sending a full tablet report...
I20260812 06:37:14.140228 26465 catalog_manager.cc:5719] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 reported cstate change: term changed from 0 to 1, leader changed from <none> to fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1). New cstate: current_term: 1 leader_uuid: "fdc3a862e93348f494337b3c43cc4cb4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } health_report { overall_health: HEALTHY } } }
I20260812 06:37:14.152091 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 26559
I20260812 06:37:14.166766 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 26418
I20260812 06:37:14.294524 26811 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:14.294735 26811 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:14.301985 26811 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/data/instance:
uuid: "6100ede5793b446b8760c1f085488cfa"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:14.305147 26811 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal/instance:
uuid: "6100ede5793b446b8760c1f085488cfa"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:14.313843 26811 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.003s	sys 0.001s
I20260812 06:37:14.440922 26822 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:14.441139 26822 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:14.564230 26828 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:14.564435 26828 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:14.569984 26828 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/data/instance:
uuid: "05c5ce1d7235466e8411a134225d94f8"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:14.573487 26828 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal/instance:
uuid: "05c5ce1d7235466e8411a134225d94f8"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:14.582650 26828 fs_manager.cc:696] Time spent creating directory manager: real 0.009s	user 0.004s	sys 0.001s
I20260812 06:37:14.711257 26844 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:14.711488 26844 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:14.835700 26850 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:14.835911 26850 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:14.840492 26850 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.002s	sys 0.001s
I20260812 06:37:14.840939 26850 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
uuid: "bfb892b2fa954f8ea24cbca371deef08"
format_stamp: "Formatted at 2026-08-12 06:37:13 on dist-test-slave-zh2d"
I20260812 06:37:14.841296 26850 tool_action_local_replica.cc:573] Rewriting Raft config of tablet: 00000000000000000000000000000000
I20260812 06:37:14.844194 26850 tool_action_local_replica.cc:538] Backed up old consensus metadata to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal/consensus-meta/00000000000000000000000000000000.pre_rewrite.1786516634841327
I20260812 06:37:14.860211 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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.200.254:37995
--webserver_interface=127.25.200.254
--webserver_port=45989
--builtin_ntp_servers=127.0.0.1:45961
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.200.254:37995 with env {}
W20260812 06:37:14.972792 26862 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:14.973045 26862 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:14.973089 26862 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:14.976438 26862 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:14.976490 26862 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:14.976511 26862 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:14.976527 26862 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:14.980366 26862 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:45961
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.25.200.254:37995
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.200.254:37995
--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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.25.200.254
--webserver_port=45989
--never_fsync=true
--heap_profile_path=/tmp/kudu.26862
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:14.981853 26862 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:14.983086 26862 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:14.989089 26871 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:14.989167 26862 server_base.cc:1061] running on GCE node
W20260812 06:37:14.989094 26870 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:14.989125 26873 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:14.989840 26862 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:14.990794 26862 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:14.992005 26862 hybrid_clock.cc:648] HybridClock initialized: now 1786516634991987 us; error 35 us; skew 500 ppm
I20260812 06:37:14.994232 26862 webserver.cc:533] Webserver started at http://127.25.200.254:45989/ using document root <none> and password file <none>
I20260812 06:37:14.994899 26862 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:14.994998 26862 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:14.998728 26862 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.004s	sys 0.000s
I20260812 06:37:15.001000 26885 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:15.001998 26862 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:15.002131 26862 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
uuid: "bfb892b2fa954f8ea24cbca371deef08"
format_stamp: "Formatted at 2026-08-12 06:37:13 on dist-test-slave-zh2d"
I20260812 06:37:15.002538 26862 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:15.041356 26862 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:15.042150 26862 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:15.042356 26862 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:15.050365 26964 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.200.254:37995 every 8 connection(s)
I20260812 06:37:15.050370 26862 rpc_server.cc:307] RPC server started. Bound to: 127.25.200.254:37995
I20260812 06:37:15.051580 26862 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:15.056027 26966 sys_catalog.cc:263] Verifying existing consensus state
W20260812 06:37:15.056816 26966 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 06:37:15.057196 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 26862
I20260812 06:37:15.057600 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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:46085
--local_ip_for_outbound_sockets=127.0.0.1
--tserver_master_addrs=127.25.200.254:37995
--webserver_port=44673
--webserver_interface=127.0.0.1
--builtin_ntp_servers=127.0.0.1:45961
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
I20260812 06:37:15.057739 26966 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Bootstrap starting.
I20260812 06:37:15.078610 26966 log.cc:826] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:15.088454 26966 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: 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 06:37:15.088929 26966 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Bootstrap complete.
I20260812 06:37:15.093998 26966 raft_consensus.cc:359] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:15.094406 26966 raft_consensus.cc:740] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: bfb892b2fa954f8ea24cbca371deef08, State: Initialized, Role: FOLLOWER
I20260812 06:37:15.095013 26966 consensus_queue.cc:260] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:15.095528 26970 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:15.095661 26970 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:15.096266 26966 sys_catalog.cc:565] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:15.101604 26988 catalog_manager.cc:1295] Loaded cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:15.101670 26988 catalog_manager.cc:1588] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: loading cluster ID for follower catalog manager: success
I20260812 06:37:15.104068 26988 catalog_manager.cc:1610] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: acquiring CA information for follower catalog manager: success
I20260812 06:37:15.105358 26988 catalog_manager.cc:1638] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
W20260812 06:37:15.183462 26968 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:15.183698 26968 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:15.183784 26968 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:15.187150 26968 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:15.187322 26968 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.0.1
I20260812 06:37:15.191287 26968 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:45961
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.0.0.1:46085
--rpc_server_allow_ephemeral_ports=true
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.0.1
--webserver_port=44673
--tserver_master_addrs=127.25.200.254:37995
--never_fsync=true
--heap_profile_path=/tmp/kudu.26968
--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 06:37:15.192448 26968 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:15.193743 26968 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:15.200809 27002 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:15.200762 26995 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:15.201043 26968 server_base.cc:1061] running on GCE node
W20260812 06:37:15.201231 26999 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:15.201611 26968 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:15.202245 26968 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:15.203426 26968 hybrid_clock.cc:648] HybridClock initialized: now 1786516635203408 us; error 34 us; skew 500 ppm
I20260812 06:37:15.205431 26968 webserver.cc:533] Webserver started at http://127.0.0.1:44673/ using document root <none> and password file <none>
I20260812 06:37:15.206037 26968 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:15.206106 26968 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:15.209626 26968 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:15.211688 27013 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:15.212769 26968 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
I20260812 06:37:15.212924 26968 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
uuid: "fdc3a862e93348f494337b3c43cc4cb4"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:15.213332 26968 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:15.245543 26968 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:15.246289 26968 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:15.246500 26968 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:15.247195 26968 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:15.248603 27030 ts_tablet_manager.cc:542] Loading tablet metadata (0/1 complete)
I20260812 06:37:15.252106 26968 ts_tablet_manager.cc:585] Loaded tablet metadata (1 total tablets, 1 live tablets)
I20260812 06:37:15.252170 26968 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.004s	user 0.001s	sys 0.000s
I20260812 06:37:15.252219 26968 ts_tablet_manager.cc:600] Registering tablets (0/1 complete)
I20260812 06:37:15.253896 26968 ts_tablet_manager.cc:616] Registered 1 tablets
I20260812 06:37:15.253954 26968 ts_tablet_manager.cc:595] Time spent register tablets: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:37:15.254328 27030 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap starting.
I20260812 06:37:15.267673 26968 rpc_server.cc:307] RPC server started. Bound to: 127.0.0.1:46085
I20260812 06:37:15.267727 27210 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.0.1:46085 every 8 connection(s)
I20260812 06:37:15.269233 26968 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:15.273864 27030 log.cc:826] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:15.275256 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 26968
I20260812 06:37:15.276780 27030 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: 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 06:37:15.277277 27030 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap complete.
I20260812 06:37:15.278837 27030 ts_tablet_manager.cc:1403] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Time spent bootstrapping tablet: real 0.025s	user 0.013s	sys 0.010s
I20260812 06:37:15.282300 27211 heartbeater.cc:344] Connected to a master server at 127.25.200.254:37995
I20260812 06:37:15.282588 27211 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:15.282299 27030 raft_consensus.cc:359] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:15.282722 27030 raft_consensus.cc:740] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Initialized, Role: FOLLOWER
I20260812 06:37:15.283205 27211 heartbeater.cc:507] Master 127.25.200.254:37995 requested a full tablet report, sending...
I20260812 06:37:15.283195 27030 consensus_queue.cc:260] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:15.283352 27030 raft_consensus.cc:399] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:15.283419 27030 raft_consensus.cc:493] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:15.283524 27030 raft_consensus.cc:3060] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Advancing to term 2
I20260812 06:37:15.285250 27030 raft_consensus.cc:515] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:15.285589 27030 leader_election.cc:304] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: fdc3a862e93348f494337b3c43cc4cb4; no voters: 
I20260812 06:37:15.285936 27030 leader_election.cc:290] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [CANDIDATE]: Term 2 election: Requested vote from peers 
I20260812 06:37:15.286056 27224 raft_consensus.cc:2804] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 FOLLOWER]: Leader election won for term 2
I20260812 06:37:15.286301 27224 raft_consensus.cc:697] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 LEADER]: Becoming Leader. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Running, Role: LEADER
I20260812 06:37:15.286639 27224 consensus_queue.cc:237] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:15.286793 27030 ts_tablet_manager.cc:1434] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Time spent starting tablet: real 0.008s	user 0.007s	sys 0.002s
I20260812 06:37:15.288441 26912 ts_manager.cc:194] Registered new tserver with Master: fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1:46085)
I20260812 06:37:15.295049 26403 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 06:37:15.410715 27236 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:15.410964 27236 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:15.415833 27236 fs_manager.cc:714] Time spent opening directory manager: real 0.004s	user 0.006s	sys 0.000s
I20260812 06:37:15.418366 27248 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:15.419211 27236 fs_report.cc:389] FS layout report
--------------------
wal directory: 
metadata directory: 
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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 06:37:15.419646 27236 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.000s
I20260812 06:37:15.419716 27236 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal
uuid: "6100ede5793b446b8760c1f085488cfa"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
W20260812 06:37:15.425590 27254 tool_action_local_replica.cc:343] Start to copy tablet 00000000000000000000000000000000
I20260812 06:37:15.426435 27254 tablet_copy_client.cc:323] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: tablet copy: Beginning tablet copy session from remote peer at address 127.25.200.254:37995
I20260812 06:37:15.434862 26940 tablet_copy_service.cc:140] P bfb892b2fa954f8ea24cbca371deef08: Received BeginTabletCopySession request for tablet 00000000000000000000000000000000 from peer 6100ede5793b446b8760c1f085488cfa ({username='slave'} at 127.0.0.1:33644)
I20260812 06:37:15.435072 26940 tablet_copy_service.cc:161] P bfb892b2fa954f8ea24cbca371deef08: Beginning new tablet copy session on tablet 00000000000000000000000000000000 from peer 6100ede5793b446b8760c1f085488cfa at {username='slave'} at 127.0.0.1:33644: session id = 6100ede5793b446b8760c1f085488cfa-00000000000000000000000000000000
I20260812 06:37:15.436265 26940 tablet_copy_source_session.cc:215] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Tablet Copy: opened 0 blocks and 1 log segments
I20260812 06:37:15.440514 27254 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total
I20260812 06:37:15.449684 27254 tablet_copy_client.cc:806] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: tablet copy: Starting download of 0 data blocks...
I20260812 06:37:15.451340 27254 tablet_copy_client.cc:670] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: tablet copy: Starting download of 1 WAL segments...
I20260812 06:37:15.455375 27254 tablet_copy_client.cc:538] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: tablet copy: Tablet Copy complete. Replacing tablet superblock.
I20260812 06:37:15.460415 27254 tool_action_local_replica.cc:343] Tablet 00000000000000000000000000000000 copy succeed.
I20260812 06:37:15.460465 27254 tool_action_local_replica.cc:343] 1/1 tablets, 2950 bytes copied, include 0 failed tablets.
I20260812 06:37:15.461294 26940 tablet_copy_service.cc:342] P bfb892b2fa954f8ea24cbca371deef08: Request end of tablet copy session 6100ede5793b446b8760c1f085488cfa-00000000000000000000000000000000 received from {username='slave'} at 127.0.0.1:33644
I20260812 06:37:15.461417 26940 tablet_copy_service.cc:434] P bfb892b2fa954f8ea24cbca371deef08: ending tablet copy session 6100ede5793b446b8760c1f085488cfa-00000000000000000000000000000000 on tablet 00000000000000000000000000000000 with peer 6100ede5793b446b8760c1f085488cfa
I20260812 06:37:15.591035 27265 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:15.591262 27265 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:15.596164 27265 fs_manager.cc:714] Time spent opening directory manager: real 0.004s	user 0.006s	sys 0.000s
I20260812 06:37:15.598642 27271 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:15.599519 27265 fs_report.cc:389] FS layout report
--------------------
wal directory: 
metadata directory: 
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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 06:37:15.599960 27265 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:37:15.600028 27265 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal
uuid: "05c5ce1d7235466e8411a134225d94f8"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
W20260812 06:37:15.605912 27278 tool_action_local_replica.cc:343] Start to copy tablet 00000000000000000000000000000000
I20260812 06:37:15.606789 27278 tablet_copy_client.cc:323] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: tablet copy: Beginning tablet copy session from remote peer at address 127.25.200.254:37995
I20260812 06:37:15.614610 26940 tablet_copy_service.cc:140] P bfb892b2fa954f8ea24cbca371deef08: Received BeginTabletCopySession request for tablet 00000000000000000000000000000000 from peer 05c5ce1d7235466e8411a134225d94f8 ({username='slave'} at 127.0.0.1:33650)
I20260812 06:37:15.614723 26940 tablet_copy_service.cc:161] P bfb892b2fa954f8ea24cbca371deef08: Beginning new tablet copy session on tablet 00000000000000000000000000000000 from peer 05c5ce1d7235466e8411a134225d94f8 at {username='slave'} at 127.0.0.1:33650: session id = 05c5ce1d7235466e8411a134225d94f8-00000000000000000000000000000000
I20260812 06:37:15.615543 26940 tablet_copy_source_session.cc:215] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Tablet Copy: opened 0 blocks and 1 log segments
I20260812 06:37:15.618782 27278 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total
I20260812 06:37:15.626996 27278 tablet_copy_client.cc:806] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: tablet copy: Starting download of 0 data blocks...
I20260812 06:37:15.628615 27278 tablet_copy_client.cc:670] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: tablet copy: Starting download of 1 WAL segments...
I20260812 06:37:15.632532 27278 tablet_copy_client.cc:538] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: tablet copy: Tablet Copy complete. Replacing tablet superblock.
I20260812 06:37:15.637302 27278 tool_action_local_replica.cc:343] Tablet 00000000000000000000000000000000 copy succeed.
I20260812 06:37:15.637352 27278 tool_action_local_replica.cc:343] 1/1 tablets, 2950 bytes copied, include 0 failed tablets.
I20260812 06:37:15.637907 26940 tablet_copy_service.cc:342] P bfb892b2fa954f8ea24cbca371deef08: Request end of tablet copy session 05c5ce1d7235466e8411a134225d94f8-00000000000000000000000000000000 received from {username='slave'} at 127.0.0.1:33650
I20260812 06:37:15.638013 26940 tablet_copy_service.cc:434] P bfb892b2fa954f8ea24cbca371deef08: ending tablet copy session 05c5ce1d7235466e8411a134225d94f8-00000000000000000000000000000000 on tablet 00000000000000000000000000000000 with peer 05c5ce1d7235466e8411a134225d94f8
I20260812 06:37:15.652962 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 26968
I20260812 06:37:15.672807 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 26862
2026-08-12T06:37:15Z chronyd exiting
2026-08-12T06:37:15Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T06:37:15Z Disabled control of system clock
I20260812 06:37:15.710417 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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.200.254:37995
--webserver_interface=127.25.200.254
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:37417
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.200.254:37995,127.25.200.253:46791,127.25.200.252:42861 with env {}
W20260812 06:37:15.821007 27294 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:15.821223 27294 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:15.821259 27294 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:15.824465 27294 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:15.824519 27294 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:15.824539 27294 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:15.824558 27294 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:15.828382 27294 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37417
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.25.200.254:37995,127.25.200.253:46791,127.25.200.252:42861
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.200.254:37995
--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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.25.200.254
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.27294
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:15.829597 27294 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:15.830744 27294 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:15.836788 27300 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:15.836788 27299 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:15.836941 27302 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:15.837013 27294 server_base.cc:1061] running on GCE node
I20260812 06:37:15.837666 27294 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:15.838604 27294 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:15.839761 27294 hybrid_clock.cc:648] HybridClock initialized: now 1786516635839731 us; error 42 us; skew 500 ppm
I20260812 06:37:15.841617 27294 webserver.cc:533] Webserver started at http://127.25.200.254:32911/ using document root <none> and password file <none>
I20260812 06:37:15.842200 27294 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:15.842257 27294 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:15.845669 27294 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:15.847637 27311 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:15.848660 27294 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:15.848783 27294 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
uuid: "bfb892b2fa954f8ea24cbca371deef08"
format_stamp: "Formatted at 2026-08-12 06:37:13 on dist-test-slave-zh2d"
I20260812 06:37:15.849207 27294 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:15.867539 27294 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:15.868566 27294 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:15.868765 27294 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:15.877213 27294 rpc_server.cc:307] RPC server started. Bound to: 127.25.200.254:37995
I20260812 06:37:15.877264 27402 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.200.254:37995 every 8 connection(s)
I20260812 06:37:15.878520 27294 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:15.884747 27403 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:15.886013 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 27294
I20260812 06:37:15.886137 26403 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-0/wal/instance
I20260812 06:37:15.886919 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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.200.253:46791
--webserver_interface=127.25.200.253
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:37417
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.200.254:37995,127.25.200.253:46791,127.25.200.252:42861 with env {}
I20260812 06:37:15.887488 27403 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Bootstrap starting.
I20260812 06:37:15.910730 27403 log.cc:826] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:15.922433 27403 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: 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 06:37:15.922942 27403 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Bootstrap complete.
I20260812 06:37:15.929374 27403 raft_consensus.cc:359] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:15.929927 27403 raft_consensus.cc:740] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: bfb892b2fa954f8ea24cbca371deef08, State: Initialized, Role: FOLLOWER
I20260812 06:37:15.930805 27403 consensus_queue.cc:260] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:15.931408 27409 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:15.931566 27409 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:15.932380 27403 sys_catalog.cc:565] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:15.938493 27424 catalog_manager.cc:1295] Loaded cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:15.938565 27424 catalog_manager.cc:1588] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: loading cluster ID for follower catalog manager: success
I20260812 06:37:15.940239 27424 catalog_manager.cc:1610] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: acquiring CA information for follower catalog manager: success
I20260812 06:37:15.941331 27424 catalog_manager.cc:1638] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:15.965166 27294 master_runner.cc:187] Error getting master registration for 127.25.200.253:46791: Network error: Client connection negotiation failed: client connection to 127.25.200.253:46791: connect: Connection refused (error 111), 
I20260812 06:37:15.968166 27294 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:16.049566 27406 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:16.049872 27406 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:16.049932 27406 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:16.054704 27406 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:16.054786 27406 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:16.054818 27406 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:16.054845 27406 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:16.060384 27406 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37417
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal
--ipki_ca_key_size=768
--master_addresses=127.25.200.254:37995,127.25.200.253:46791,127.25.200.252:42861
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.200.253:46791
--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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/data/info.pb
--webserver_interface=127.25.200.253
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.27406
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:16.061865 27406 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:16.063333 27406 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:16.069862 27434 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:16.069970 27433 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:16.070205 27437 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:16.070529 27406 server_base.cc:1061] running on GCE node
I20260812 06:37:16.071081 27406 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:16.072168 27406 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:16.073351 27406 hybrid_clock.cc:648] HybridClock initialized: now 1786516636073334 us; error 40 us; skew 500 ppm
I20260812 06:37:16.075557 27406 webserver.cc:533] Webserver started at http://127.25.200.253:38139/ using document root <none> and password file <none>
I20260812 06:37:16.076253 27406 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:16.076336 27406 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:16.080919 27406 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:37:16.083531 27443 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:16.084925 27406 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:37:16.085112 27406 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal
uuid: "6100ede5793b446b8760c1f085488cfa"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:16.085677 27406 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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 06:37:16.120231 27406 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:16.121248 27406 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:16.121536 27406 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:16.131464 27406 rpc_server.cc:307] RPC server started. Bound to: 127.25.200.253:46791
I20260812 06:37:16.131711 27525 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.200.253:46791 every 8 connection(s)
I20260812 06:37:16.133082 27406 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/data/info.pb
I20260812 06:37:16.140007 27527 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:16.141744 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 27406
I20260812 06:37:16.141865 26403 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-1/wal/instance
I20260812 06:37:16.142601 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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.200.252:42861
--webserver_interface=127.25.200.252
--webserver_port=0
--builtin_ntp_servers=127.0.0.1:37417
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.25.200.254:37995,127.25.200.253:46791,127.25.200.252:42861 with env {}
I20260812 06:37:16.143009 27527 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: Bootstrap starting.
I20260812 06:37:16.153379 27527 log.cc:826] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:16.165495 27527 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: 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 06:37:16.165901 27527 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: Bootstrap complete.
I20260812 06:37:16.169267 27527 raft_consensus.cc:359] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:16.169657 27527 raft_consensus.cc:740] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: 6100ede5793b446b8760c1f085488cfa, State: Initialized, Role: FOLLOWER
I20260812 06:37:16.170320 27527 consensus_queue.cc:260] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:16.170861 27542 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:16.171063 27542 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:16.171650 27527 sys_catalog.cc:565] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:16.179378 27561 catalog_manager.cc:1295] Loaded cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:16.179519 27561 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: loading cluster ID for follower catalog manager: success
I20260812 06:37:16.182133 27561 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: acquiring CA information for follower catalog manager: success
I20260812 06:37:16.183815 27561 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:16.209482 27406 master_runner.cc:187] Error getting master registration for 127.25.200.252:42861: Network error: Client connection negotiation failed: client connection to 127.25.200.252:42861: connect: Connection refused (error 111), 
I20260812 06:37:16.211620 27406 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:16.273523 27535 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:16.273722 27535 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:16.273784 27535 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:16.277298 27535 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:16.277376 27535 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:16.277411 27535 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:16.277462 27535 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
I20260812 06:37:16.281562 27535 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37417
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal
--ipki_ca_key_size=768
--master_addresses=127.25.200.254:37995,127.25.200.253:46791,127.25.200.252:42861
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.25.200.252:42861
--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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/data/info.pb
--webserver_interface=127.25.200.252
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.27535
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:16.282752 27535 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:16.283968 27535 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:16.289701 27578 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:16.289727 27573 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:16.289695 27575 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:16.290174 27535 server_base.cc:1061] running on GCE node
I20260812 06:37:16.290668 27535 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:16.291611 27535 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:16.292804 27535 hybrid_clock.cc:648] HybridClock initialized: now 1786516636292789 us; error 32 us; skew 500 ppm
I20260812 06:37:16.294911 27535 webserver.cc:533] Webserver started at http://127.25.200.252:42243/ using document root <none> and password file <none>
I20260812 06:37:16.295493 27535 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:16.295579 27535 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:16.299029 27535 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:16.301100 27584 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:16.302135 27535 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
I20260812 06:37:16.302228 27535 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal
uuid: "05c5ce1d7235466e8411a134225d94f8"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:16.302707 27535 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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 06:37:16.318106 27535 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:16.318789 27535 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:16.318985 27535 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:16.328168 27666 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.200.252:42861 every 8 connection(s)
I20260812 06:37:16.328168 27535 rpc_server.cc:307] RPC server started. Bound to: 127.25.200.252:42861
I20260812 06:37:16.329392 27535 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/data/info.pb
I20260812 06:37:16.330226 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 27535
I20260812 06:37:16.330500 26403 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/master-2/wal/instance
I20260812 06:37:16.334996 27668 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:16.337270 27668 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: Bootstrap starting.
I20260812 06:37:16.344614 27668 log.cc:826] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:16.353307 27668 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: 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 06:37:16.353649 27668 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: Bootstrap complete.
I20260812 06:37:16.357149 27668 raft_consensus.cc:359] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:16.357478 27668 raft_consensus.cc:740] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: 05c5ce1d7235466e8411a134225d94f8, State: Initialized, Role: FOLLOWER
I20260812 06:37:16.357944 27668 consensus_queue.cc:260] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:16.358429 27674 sys_catalog.cc:455] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:16.358558 27674 sys_catalog.cc:458] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:16.359108 27668 sys_catalog.cc:565] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:16.365149 27691 catalog_manager.cc:1295] Loaded cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:16.365221 27691 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: loading cluster ID for follower catalog manager: success
I20260812 06:37:16.367600 27691 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: acquiring CA information for follower catalog manager: success
I20260812 06:37:16.369011 27691 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:16.387050 27535 master_runner.cc:264] No leader master found from master 05c5ce1d7235466e8411a134225d94f8
I20260812 06:37:16.387151 27535 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
I20260812 06:37:17.297010 27695 raft_consensus.cc:493] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 1 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:17.297253 27695 raft_consensus.cc:515] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 1 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:17.298578 27695 leader_election.cc:290] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [CANDIDATE]: Term 2 pre-election: Requested pre-vote from peers bfb892b2fa954f8ea24cbca371deef08 (127.25.200.254:37995), 05c5ce1d7235466e8411a134225d94f8 (127.25.200.252:42861)
I20260812 06:37:17.299301 27361 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "6100ede5793b446b8760c1f085488cfa" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "bfb892b2fa954f8ea24cbca371deef08" is_pre_election: true
I20260812 06:37:17.299680 27361 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 6100ede5793b446b8760c1f085488cfa in term 1.
I20260812 06:37:17.300230 27445 leader_election.cc:304] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [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: 6100ede5793b446b8760c1f085488cfa, bfb892b2fa954f8ea24cbca371deef08; no voters: 
I20260812 06:37:17.300531 27695 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 1 FOLLOWER]: Leader pre-election won for term 2
I20260812 06:37:17.300621 27695 raft_consensus.cc:493] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 1 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:17.300681 27695 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 1 FOLLOWER]: Advancing to term 2
I20260812 06:37:17.302923 27695 raft_consensus.cc:515] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 2 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:17.303359 27695 leader_election.cc:290] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [CANDIDATE]: Term 2 election: Requested vote from peers bfb892b2fa954f8ea24cbca371deef08 (127.25.200.254:37995), 05c5ce1d7235466e8411a134225d94f8 (127.25.200.252:42861)
I20260812 06:37:17.303294 27625 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "6100ede5793b446b8760c1f085488cfa" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "05c5ce1d7235466e8411a134225d94f8" is_pre_election: true
I20260812 06:37:17.303607 27625 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 1 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 6100ede5793b446b8760c1f085488cfa in term 1.
I20260812 06:37:17.303767 27623 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "6100ede5793b446b8760c1f085488cfa" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "05c5ce1d7235466e8411a134225d94f8"
I20260812 06:37:17.303804 27361 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "6100ede5793b446b8760c1f085488cfa" candidate_term: 2 candidate_status { last_received { term: 1 index: 7 } } ignore_live_leader: false dest_uuid: "bfb892b2fa954f8ea24cbca371deef08"
I20260812 06:37:17.303864 27623 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 1 FOLLOWER]: Advancing to term 2
I20260812 06:37:17.303977 27361 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 1 FOLLOWER]: Advancing to term 2
I20260812 06:37:17.305552 27623 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 6100ede5793b446b8760c1f085488cfa in term 2.
I20260812 06:37:17.305843 27446 leader_election.cc:304] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [CANDIDATE]: Term 2 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 05c5ce1d7235466e8411a134225d94f8, 6100ede5793b446b8760c1f085488cfa; no voters: 
I20260812 06:37:17.305850 27361 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 2 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 6100ede5793b446b8760c1f085488cfa in term 2.
I20260812 06:37:17.306015 27695 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 2 FOLLOWER]: Leader election won for term 2
I20260812 06:37:17.306216 27695 raft_consensus.cc:697] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 2 LEADER]: Becoming Leader. State: Replica: 6100ede5793b446b8760c1f085488cfa, State: Running, Role: LEADER
I20260812 06:37:17.306535 27695 consensus_queue.cc:237] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:17.308379 27704 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: SysCatalogTable state changed. Reason: New leader 6100ede5793b446b8760c1f085488cfa. Latest consensus state: current_term: 2 leader_uuid: "6100ede5793b446b8760c1f085488cfa" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:17.308506 27704 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:17.308911 27709 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:17.310560 27709 catalog_manager.cc:679] Loaded metadata for table test [id=cccdb7513d80485390c14b18d69dc055]
I20260812 06:37:17.311681 27709 tablet_loader.cc:96] loaded metadata for tablet 87f8e3e6f5844329a455cce463bfdcde (table test [id=cccdb7513d80485390c14b18d69dc055])
I20260812 06:37:17.311829 27709 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:17.312237 27709 catalog_manager.cc:1295] Loaded cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:17.312302 27709 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:17.312826 27709 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:17.313256 27709 catalog_manager.cc:6103] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa: Loaded TSK: 0
I20260812 06:37:17.313808 27709 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:17.323066 26403 external_mini_cluster.cc:1372] Running /tmp/dist-test-task0Fisrt/build/debug/bin/kudu
/tmp/dist-test-task0Fisrt/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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.200.254:37995,127.25.200.253:46791,127.25.200.252:42861
--builtin_ntp_servers=127.0.0.1:37417
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin with env {}
W20260812 06:37:17.436480 27711 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:17.436743 27711 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:17.436825 27711 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:17.440352 27711 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:17.440529 27711 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.0.1
I20260812 06:37:17.444476 27711 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.0.1:37417
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-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-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.0.1
--webserver_port=0
--tserver_master_addrs=127.25.200.254:37995,127.25.200.253:46791,127.25.200.252:42861
--never_fsync=true
--heap_profile_path=/tmp/kudu.27711
--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 06:37:17.445734 27711 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:17.446974 27711 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:37:17.454253 27724 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:17.454252 27720 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:17.454257 27718 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:17.454623 27711 server_base.cc:1061] running on GCE node
I20260812 06:37:17.454998 27711 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:17.455569 27711 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:17.456760 27711 hybrid_clock.cc:648] HybridClock initialized: now 1786516637456736 us; error 31 us; skew 500 ppm
I20260812 06:37:17.458948 27711 webserver.cc:533] Webserver started at http://127.0.0.1:38309/ using document root <none> and password file <none>
I20260812 06:37:17.459692 27711 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:17.459755 27711 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:17.463605 27711 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.001s	sys 0.001s
I20260812 06:37:17.465794 27731 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:17.466812 27711 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:17.466962 27711 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data,/tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
uuid: "fdc3a862e93348f494337b3c43cc4cb4"
format_stamp: "Formatted at 2026-08-12 06:37:14 on dist-test-slave-zh2d"
I20260812 06:37:17.467403 27711 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:17.490192 27711 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:17.490912 27711 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:17.491129 27711 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:17.491819 27711 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:17.493340 27743 ts_tablet_manager.cc:542] Loading tablet metadata (0/1 complete)
I20260812 06:37:17.496784 27711 ts_tablet_manager.cc:585] Loaded tablet metadata (1 total tablets, 1 live tablets)
I20260812 06:37:17.496898 27711 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.004s	user 0.000s	sys 0.000s
I20260812 06:37:17.497017 27711 ts_tablet_manager.cc:600] Registering tablets (0/1 complete)
I20260812 06:37:17.498644 27711 ts_tablet_manager.cc:616] Registered 1 tablets
I20260812 06:37:17.498721 27711 ts_tablet_manager.cc:595] Time spent register tablets: real 0.002s	user 0.000s	sys 0.000s
I20260812 06:37:17.499015 27743 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap starting.
I20260812 06:37:17.512562 27711 rpc_server.cc:307] RPC server started. Bound to: 127.0.0.1:37265
I20260812 06:37:17.512588 27912 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.0.1:37265 every 8 connection(s)
I20260812 06:37:17.513832 27711 server_base.cc:1193] Dumped server information to /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:17.516752 27743 log.cc:826] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:17.520068 27743 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: 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 06:37:17.520104 26403 external_mini_cluster.cc:1434] Started /tmp/dist-test-task0Fisrt/build/debug/bin/kudu as pid 27711
I20260812 06:37:17.520210 26403 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task0Fisrt/test-tmp/master_migration-itest.0.MasterMigrationTest.TestEndToEndMigration.1786516633615382-26403-0/minicluster-data/ts-0/wal/instance
I20260812 06:37:17.520506 27743 tablet_bootstrap.cc:492] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap complete.
I20260812 06:37:17.521978 27743 ts_tablet_manager.cc:1403] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Time spent bootstrapping tablet: real 0.023s	user 0.010s	sys 0.012s
I20260812 06:37:17.525568 27914 heartbeater.cc:344] Connected to a master server at 127.25.200.254:37995
I20260812 06:37:17.525811 27914 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:17.525610 27743 raft_consensus.cc:359] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:17.526033 27743 raft_consensus.cc:740] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 FOLLOWER]: Becoming Follower/Learner. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Initialized, Role: FOLLOWER
I20260812 06:37:17.526607 27743 consensus_queue.cc:260] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:17.526768 27743 raft_consensus.cc:399] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:17.526818 27917 heartbeater.cc:344] Connected to a master server at 127.25.200.253:46791
I20260812 06:37:17.526844 27743 raft_consensus.cc:493] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:17.526907 27917 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:17.526978 27743 raft_consensus.cc:3060] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 2 FOLLOWER]: Advancing to term 3
I20260812 06:37:17.527472 27914 heartbeater.cc:507] Master 127.25.200.254:37995 requested a full tablet report, sending...
I20260812 06:37:17.527472 27917 heartbeater.cc:507] Master 127.25.200.253:46791 requested a full tablet report, sending...
I20260812 06:37:17.527694 27916 heartbeater.cc:344] Connected to a master server at 127.25.200.252:42861
I20260812 06:37:17.527812 27916 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:17.528038 27916 heartbeater.cc:507] Master 127.25.200.252:42861 requested a full tablet report, sending...
I20260812 06:37:17.528964 27743 raft_consensus.cc:515] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 3 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:17.529284 27743 leader_election.cc:304] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: fdc3a862e93348f494337b3c43cc4cb4; no voters: 
I20260812 06:37:17.529695 27743 leader_election.cc:290] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [CANDIDATE]: Term 3 election: Requested vote from peers 
I20260812 06:37:17.529808 27936 raft_consensus.cc:2804] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 3 FOLLOWER]: Leader election won for term 3
I20260812 06:37:17.530035 27936 raft_consensus.cc:697] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [term 3 LEADER]: Becoming Leader. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Running, Role: LEADER
I20260812 06:37:17.530325 27936 consensus_queue.cc:237] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } }
I20260812 06:37:17.530663 27743 ts_tablet_manager.cc:1434] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4: Time spent starting tablet: real 0.009s	user 0.004s	sys 0.005s
I20260812 06:37:17.532298 27466 ts_manager.cc:194] Registered new tserver with Master: fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1:37265)
I20260812 06:37:17.532552 27613 ts_manager.cc:194] Registered new tserver with Master: fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1:37265)
I20260812 06:37:17.532600 27342 ts_manager.cc:194] Registered new tserver with Master: fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1:37265)
I20260812 06:37:17.534955 27466 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:51437
I20260812 06:37:17.536438 27917 heartbeater.cc:499] Master 127.25.200.253:46791 was elected leader, sending a full tablet report...
I20260812 06:37:17.537500 27466 catalog_manager.cc:5719] T 87f8e3e6f5844329a455cce463bfdcde P fdc3a862e93348f494337b3c43cc4cb4 reported cstate change: term changed from 1 to 3. New cstate: current_term: 3 leader_uuid: "fdc3a862e93348f494337b3c43cc4cb4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 46085 } health_report { overall_health: HEALTHY } } }
I20260812 06:37:17.541112 27623 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Refusing update from remote peer 6100ede5793b446b8760c1f085488cfa: Log matching property violated. Preceding OpId in replica: term: 1 index: 7. Preceding OpId from leader: term: 2 index: 9. (index mismatch)
I20260812 06:37:17.541122 27361 raft_consensus.cc:1275] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 2 FOLLOWER]: Refusing update from remote peer 6100ede5793b446b8760c1f085488cfa: Log matching property violated. Preceding OpId in replica: term: 1 index: 7. Preceding OpId from leader: term: 2 index: 9. (index mismatch)
I20260812 06:37:17.541715 27695 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [LEADER]: Connected to new peer: Peer: permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 8, Last known committed idx: 7, Time since last communication: 0.000s
I20260812 06:37:17.541988 27704 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [LEADER]: Connected to new peer: Peer: permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 8, Last known committed idx: 7, Time since last communication: 0.000s
I20260812 06:37:17.544117 26403 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 06:37:17.545472 27946 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 6100ede5793b446b8760c1f085488cfa. Latest consensus state: current_term: 2 leader_uuid: "6100ede5793b446b8760c1f085488cfa" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:17.545518 27945 sys_catalog.cc:455] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 6100ede5793b446b8760c1f085488cfa. Latest consensus state: current_term: 2 leader_uuid: "6100ede5793b446b8760c1f085488cfa" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:17.545588 27946 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:17.545614 27945 sys_catalog.cc:458] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:17.546772 27695 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 2 leader_uuid: "6100ede5793b446b8760c1f085488cfa" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:17.546887 27695 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:17.547011 27942 mvcc.cc:204] Tried to move back new op lower bound from 7317572147361918976 to 7317572146410315776. Current Snapshot: MvccSnapshot[applied={T|T < 7317572147361918976}]
I20260812 06:37:17.548305 27948 mvcc.cc:204] Tried to move back new op lower bound from 7317572147361918976 to 7317572146410315776. Current Snapshot: MvccSnapshot[applied={T|T < 7317572147361918976}]
I20260812 06:37:17.548465 27946 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 2 leader_uuid: "6100ede5793b446b8760c1f085488cfa" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:17.548561 27946 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:17.549311 27695 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 2 leader_uuid: "6100ede5793b446b8760c1f085488cfa" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:17.549412 27695 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:17.550508 27947 mvcc.cc:204] Tried to move back new op lower bound from 7317572147361918976 to 7317572146410315776. Current Snapshot: MvccSnapshot[applied={T|T < 7317572147361918976}]
I20260812 06:37:17.550663 27945 sys_catalog.cc:455] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 2 leader_uuid: "6100ede5793b446b8760c1f085488cfa" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:17.550779 27945 sys_catalog.cc:458] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:17.583630 27466 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:45052:
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 06:37:17.600225 27806 tablet_service.cc:1511] Processing CreateTablet for tablet 4e4ebb4952354aeb9f085d05346b1cc4 (DEFAULT_TABLE table=second_table [id=57cdb17a5a684f798489fa9488112bf6]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:17.600950 27806 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 4e4ebb4952354aeb9f085d05346b1cc4. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:17.604123 27743 tablet_bootstrap.cc:492] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap starting.
I20260812 06:37:17.605228 27743 tablet_bootstrap.cc:654] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:17.606387 27743 tablet_bootstrap.cc:492] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4: No bootstrap required, opened a new log
I20260812 06:37:17.606499 27743 ts_tablet_manager.cc:1403] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:17.607200 27743 raft_consensus.cc:359] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.607358 27743 raft_consensus.cc:385] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:17.607434 27743 raft_consensus.cc:740] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Initialized, Role: FOLLOWER
I20260812 06:37:17.607607 27743 consensus_queue.cc:260] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.607684 27743 raft_consensus.cc:399] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:17.607739 27743 raft_consensus.cc:493] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:17.607798 27743 raft_consensus.cc:3060] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:17.608496 27743 raft_consensus.cc:515] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.608646 27743 leader_election.cc:304] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [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: fdc3a862e93348f494337b3c43cc4cb4; no voters: 
I20260812 06:37:17.608779 27743 leader_election.cc:290] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:17.608887 27938 raft_consensus.cc:2804] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:17.609066 27938 raft_consensus.cc:697] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [term 1 LEADER]: Becoming Leader. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Running, Role: LEADER
I20260812 06:37:17.609233 27938 consensus_queue.cc:237] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.609427 27743 ts_tablet_manager.cc:1434] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:37:17.610905 27466 catalog_manager.cc:5719] T 4e4ebb4952354aeb9f085d05346b1cc4 P fdc3a862e93348f494337b3c43cc4cb4 reported cstate change: term changed from 0 to 1, leader changed from <none> to fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1). New cstate: current_term: 1 leader_uuid: "fdc3a862e93348f494337b3c43cc4cb4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } health_report { overall_health: HEALTHY } } }
I20260812 06:37:17.643591 27466 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:45058:
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 06:37:17.652765 27806 tablet_service.cc:1511] Processing CreateTablet for tablet 4f80a62e17a041f9a518659a72f158df (DEFAULT_TABLE table=table-0 [id=c0e9c1c88e8344e6b84f3ce6897fed29]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:17.653159 27806 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 4f80a62e17a041f9a518659a72f158df. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:17.655458 27743 tablet_bootstrap.cc:492] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap starting.
I20260812 06:37:17.656401 27743 tablet_bootstrap.cc:654] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:17.657740 27743 tablet_bootstrap.cc:492] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4: No bootstrap required, opened a new log
I20260812 06:37:17.657853 27743 ts_tablet_manager.cc:1403] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:17.658303 27743 raft_consensus.cc:359] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.658419 27743 raft_consensus.cc:385] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:17.658466 27743 raft_consensus.cc:740] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Initialized, Role: FOLLOWER
I20260812 06:37:17.658608 27743 consensus_queue.cc:260] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.658715 27743 raft_consensus.cc:399] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:17.658758 27743 raft_consensus.cc:493] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:17.658815 27743 raft_consensus.cc:3060] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:17.659669 27743 raft_consensus.cc:515] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.659818 27743 leader_election.cc:304] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [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: fdc3a862e93348f494337b3c43cc4cb4; no voters: 
I20260812 06:37:17.659992 27936 raft_consensus.cc:2804] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:17.660097 27936 raft_consensus.cc:697] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [term 1 LEADER]: Becoming Leader. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Running, Role: LEADER
I20260812 06:37:17.660226 27936 consensus_queue.cc:237] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:17.660817 27743 leader_election.cc:290] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:17.661191 27743 ts_tablet_manager.cc:1434] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4: Time spent starting tablet: real 0.003s	user 0.002s	sys 0.001s
I20260812 06:37:17.662405 27466 catalog_manager.cc:5719] T 4f80a62e17a041f9a518659a72f158df P fdc3a862e93348f494337b3c43cc4cb4 reported cstate change: term changed from 0 to 1, leader changed from <none> to fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1). New cstate: current_term: 1 leader_uuid: "fdc3a862e93348f494337b3c43cc4cb4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } health_report { overall_health: HEALTHY } } }
I20260812 06:37:19.166977 28005 raft_consensus.cc:493] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Starting pre-election (detected failure of leader 6100ede5793b446b8760c1f085488cfa)
I20260812 06:37:19.167141 28005 raft_consensus.cc:515] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:19.168568 28005 leader_election.cc:290] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [CANDIDATE]: Term 3 pre-election: Requested pre-vote from peers bfb892b2fa954f8ea24cbca371deef08 (127.25.200.254:37995), 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791)
I20260812 06:37:19.169270 27361 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "bfb892b2fa954f8ea24cbca371deef08" is_pre_election: true
I20260812 06:37:19.171816 28007 raft_consensus.cc:493] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 2 FOLLOWER]: Starting pre-election (detected failure of leader 6100ede5793b446b8760c1f085488cfa)
I20260812 06:37:19.171944 28007 raft_consensus.cc:515] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 2 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:19.173192 28007 leader_election.cc:290] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [CANDIDATE]: Term 3 pre-election: Requested pre-vote from peers 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791), 05c5ce1d7235466e8411a134225d94f8 (127.25.200.252:42861)
I20260812 06:37:19.176987 27623 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "bfb892b2fa954f8ea24cbca371deef08" candidate_term: 3 candidate_status { last_received { term: 2 index: 13 } } ignore_live_leader: false dest_uuid: "05c5ce1d7235466e8411a134225d94f8" is_pre_election: true
I20260812 06:37:19.177184 27623 raft_consensus.cc:2410] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Leader pre-election vote request: Denying vote to candidate bfb892b2fa954f8ea24cbca371deef08 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 06:37:20.714596 27588 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: RequestConsensusVote RPC to 127.25.200.253:46791 timed out after 1.545s (SENT)
W20260812 06:37:21.328990 27314 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: connection negotiation to 127.25.200.253:46791 for RPC RequestConsensusVote timed out after 2.154s (ON_OUTBOUND_QUEUE)
W20260812 06:37:22.193761 28011 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 06:37:19.173336 (+     0us) reactor.cc:730] Submitting negotiation task for client connection to 127.25.200.253:46791 (local address 127.0.0.1:45062)
0812 06:37:19.173716 (+   380us) negotiation.cc:107] Waiting for socket to connect
0812 06:37:19.173733 (+    17us) client_negotiation.cc:175] Beginning negotiation
0812 06:37:19.173855 (+   122us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:37:22.193118 (+3019263us) negotiation.cc:326] Negotiation complete: Timed out: Client connection negotiation failed: client connection to 127.25.200.253:46791: received 0 of 4 requested bytes
Metrics: {"client-negotiator.queue_time_us":272,"spinlock_wait_cycles":15360,"thread_start_us":64,"threads_started":1}
W20260812 06:37:22.194067 27314 leader_election.cc:336] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [CANDIDATE]: Term 3 pre-election: RPC error from VoteRequest() call to peer 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791): Timed out: Client connection negotiation failed: client connection to 127.25.200.253:46791: received 0 of 4 requested bytes
I20260812 06:37:22.194167 27314 leader_election.cc:304] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: bfb892b2fa954f8ea24cbca371deef08; no voters: 05c5ce1d7235466e8411a134225d94f8, 6100ede5793b446b8760c1f085488cfa
I20260812 06:37:22.194677 28015 raft_consensus.cc:2749] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 2 FOLLOWER]: Leader pre-election lost for term 3. Reason: could not achieve majority
I20260812 06:37:22.194770 27294 master_runner.cc:187] Error getting master registration for 127.25.200.253:46791: Timed out: Client connection negotiation failed: client connection to 127.25.200.253:46791: received 0 of 4 requested bytes, 
I20260812 06:37:22.194891 27294 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:22.261933 27588 leader_election.cc:336] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [CANDIDATE]: Term 3 pre-election: RPC error from VoteRequest() call to peer 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791): Timed out: RequestConsensusVote RPC to 127.25.200.253:46791 timed out after 1.545s (SENT)
I20260812 06:37:22.262097 27588 leader_election.cc:304] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [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: 05c5ce1d7235466e8411a134225d94f8; no voters: 6100ede5793b446b8760c1f085488cfa, bfb892b2fa954f8ea24cbca371deef08
I20260812 06:37:22.262540 28017 raft_consensus.cc:2749] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Leader pre-election lost for term 3. Reason: could not achieve majority
I20260812 06:37:23.887631 28019 raft_consensus.cc:493] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Starting pre-election (detected failure of leader 6100ede5793b446b8760c1f085488cfa)
I20260812 06:37:23.887840 28019 raft_consensus.cc:515] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:23.888420 28019 leader_election.cc:290] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [CANDIDATE]: Term 3 pre-election: Requested pre-vote from peers bfb892b2fa954f8ea24cbca371deef08 (127.25.200.254:37995), 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791)
I20260812 06:37:23.889026 27361 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "bfb892b2fa954f8ea24cbca371deef08" is_pre_election: true
I20260812 06:37:23.889173 27361 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 2 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 05c5ce1d7235466e8411a134225d94f8 in term 2.
I20260812 06:37:23.889752 27588 leader_election.cc:304] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [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: 05c5ce1d7235466e8411a134225d94f8, bfb892b2fa954f8ea24cbca371deef08; no voters: 
I20260812 06:37:23.890007 28019 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Leader pre-election won for term 3
I20260812 06:37:23.890074 28019 raft_consensus.cc:493] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Starting leader election (detected failure of leader 6100ede5793b446b8760c1f085488cfa)
I20260812 06:37:23.890138 28019 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 2 FOLLOWER]: Advancing to term 3
I20260812 06:37:23.891189 28019 raft_consensus.cc:515] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 3 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:23.891620 28019 leader_election.cc:290] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [CANDIDATE]: Term 3 election: Requested vote from peers bfb892b2fa954f8ea24cbca371deef08 (127.25.200.254:37995), 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791)
I20260812 06:37:23.892100 27361 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "bfb892b2fa954f8ea24cbca371deef08"
I20260812 06:37:23.892234 27361 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 2 FOLLOWER]: Advancing to term 3
I20260812 06:37:23.893247 27361 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 3 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 05c5ce1d7235466e8411a134225d94f8 in term 3.
I20260812 06:37:23.893669 27588 leader_election.cc:304] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [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: 05c5ce1d7235466e8411a134225d94f8, bfb892b2fa954f8ea24cbca371deef08; no voters: 
I20260812 06:37:23.893929 28019 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 3 FOLLOWER]: Leader election won for term 3
I20260812 06:37:23.894150 28019 raft_consensus.cc:697] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 3 LEADER]: Becoming Leader. State: Replica: 05c5ce1d7235466e8411a134225d94f8, State: Running, Role: LEADER
I20260812 06:37:23.894528 28019 consensus_queue.cc:237] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:23.895644 28021 sys_catalog.cc:455] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 05c5ce1d7235466e8411a134225d94f8. Latest consensus state: current_term: 3 leader_uuid: "05c5ce1d7235466e8411a134225d94f8" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:23.895772 28021 sys_catalog.cc:458] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:23.896103 28026 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:23.897882 28026 catalog_manager.cc:679] Loaded metadata for table second_table [id=57cdb17a5a684f798489fa9488112bf6]
I20260812 06:37:23.898198 28026 catalog_manager.cc:679] Loaded metadata for table table-0 [id=c0e9c1c88e8344e6b84f3ce6897fed29]
I20260812 06:37:23.898429 28026 catalog_manager.cc:679] Loaded metadata for table test [id=cccdb7513d80485390c14b18d69dc055]
I20260812 06:37:23.899403 28026 tablet_loader.cc:96] loaded metadata for tablet 4e4ebb4952354aeb9f085d05346b1cc4 (table second_table [id=57cdb17a5a684f798489fa9488112bf6])
I20260812 06:37:23.899641 28026 tablet_loader.cc:96] loaded metadata for tablet 4f80a62e17a041f9a518659a72f158df (table table-0 [id=c0e9c1c88e8344e6b84f3ce6897fed29])
I20260812 06:37:23.899855 28026 tablet_loader.cc:96] loaded metadata for tablet 87f8e3e6f5844329a455cce463bfdcde (table test [id=cccdb7513d80485390c14b18d69dc055])
I20260812 06:37:23.899973 28026 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:23.900375 28026 catalog_manager.cc:1295] Loaded cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:23.900420 28026 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:23.901039 28026 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:23.901440 28026 catalog_manager.cc:6103] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8: Loaded TSK: 0
I20260812 06:37:23.902118 28026 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:24.467664 27361 raft_consensus.cc:1275] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 3 FOLLOWER]: Refusing update from remote peer 05c5ce1d7235466e8411a134225d94f8: Log matching property violated. Preceding OpId in replica: term: 2 index: 13. Preceding OpId from leader: term: 3 index: 16. (index mismatch)
I20260812 06:37:24.468423 28021 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [LEADER]: Connected to new peer: Peer: permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 16, Last known committed idx: 12, Time since last communication: 0.000s
I20260812 06:37:24.471228 28027 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 05c5ce1d7235466e8411a134225d94f8. Latest consensus state: current_term: 3 leader_uuid: "05c5ce1d7235466e8411a134225d94f8" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:24.471376 28027 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:24.472154 28034 sys_catalog.cc:455] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 3 leader_uuid: "05c5ce1d7235466e8411a134225d94f8" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:24.472289 28034 sys_catalog.cc:458] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:24.472440 28027 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 3 leader_uuid: "05c5ce1d7235466e8411a134225d94f8" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:24.472582 28027 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:25.681140 27916 heartbeater.cc:499] Master 127.25.200.252:42861 was elected leader, sending a full tablet report...
W20260812 06:37:25.841905 27588 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: RequestConsensusVote RPC to 127.25.200.253:46791 timed out after 1.949s (SENT)
W20260812 06:37:27.681273 27954 master_proxy_rpc.cc:203] Re-attempting GetTableSchema request to leader Master (127.25.200.253:46791)
I20260812 06:37:27.697033 27613 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:56944:
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 06:37:27.714850 27806 tablet_service.cc:1511] Processing CreateTablet for tablet a5fcc74d865848cbb0391ba4e022c662 (DEFAULT_TABLE table=table-1 [id=c61ee077d6f34cf3954bda8db06b369b]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:27.715159 27806 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a5fcc74d865848cbb0391ba4e022c662. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:27.717005 28077 tablet_bootstrap.cc:492] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap starting.
I20260812 06:37:27.718102 28077 tablet_bootstrap.cc:654] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:27.719051 28077 tablet_bootstrap.cc:492] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4: No bootstrap required, opened a new log
I20260812 06:37:27.719126 28077 ts_tablet_manager.cc:1403] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:27.719460 28077 raft_consensus.cc:359] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:27.719545 28077 raft_consensus.cc:385] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:27.719568 28077 raft_consensus.cc:740] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Initialized, Role: FOLLOWER
I20260812 06:37:27.719671 28077 consensus_queue.cc:260] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:27.719726 28077 raft_consensus.cc:399] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:27.719749 28077 raft_consensus.cc:493] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:27.719784 28077 raft_consensus.cc:3060] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:27.720463 28077 raft_consensus.cc:515] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:27.720579 28077 leader_election.cc:304] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [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: fdc3a862e93348f494337b3c43cc4cb4; no voters: 
I20260812 06:37:27.720705 28077 leader_election.cc:290] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:27.720817 28081 raft_consensus.cc:2804] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:27.721097 28077 ts_tablet_manager.cc:1434] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4: Time spent starting tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:27.721181 28081 raft_consensus.cc:697] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [term 1 LEADER]: Becoming Leader. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Running, Role: LEADER
I20260812 06:37:27.721581 28081 consensus_queue.cc:237] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:27.723129 27613 catalog_manager.cc:5719] T a5fcc74d865848cbb0391ba4e022c662 P fdc3a862e93348f494337b3c43cc4cb4 reported cstate change: term changed from 0 to 1, leader changed from <none> to fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1). New cstate: current_term: 1 leader_uuid: "fdc3a862e93348f494337b3c43cc4cb4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } health_report { overall_health: HEALTHY } } }
I20260812 06:37:27.735836 27484 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa" is_pre_election: true
I20260812 06:37:27.735978 27474 raft_consensus.cc:3055] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 2 LEADER]: Stepping down as leader of term 2
I20260812 06:37:27.736057 27474 raft_consensus.cc:740] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 2 LEADER]: Becoming Follower/Learner. State: Replica: 6100ede5793b446b8760c1f085488cfa, State: Running, Role: LEADER
I20260812 06:37:27.736231 27474 consensus_queue.cc:260] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:27.736388 27474 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 2 FOLLOWER]: Advancing to term 3
I20260812 06:37:27.738492 27478 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa" is_pre_election: true
I20260812 06:37:27.738823 27480 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa" is_pre_election: true
I20260812 06:37:27.738817 27471 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa" is_pre_election: true
W20260812 06:37:27.739096 28090 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 06:37:27.734372 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:45062 (local address 127.25.200.253:46791)
0812 06:37:27.734828 (+   456us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:27.734833 (+     5us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:27.734853 (+    20us) server_negotiation.cc:408] Connection header received
0812 06:37:27.734941 (+    88us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:37:27.734948 (+     7us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:37:27.735062 (+   114us) server_negotiation.cc:378] Sending NEGOTIATE NegotiatePB response
0812 06:37:27.735275 (+   213us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:45062: BlockingWrite error: write error: Broken pipe (error 32)
Metrics: {"server-negotiator.queue_time_us":312,"thread_start_us":121,"threads_started":1}
I20260812 06:37:27.739400 27474 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 3 FOLLOWER]: Refusing update from remote peer 05c5ce1d7235466e8411a134225d94f8: Log matching property violated. Preceding OpId in replica: term: 2 index: 15. Preceding OpId from leader: term: 3 index: 16. (index mismatch)
I20260812 06:37:27.739782 27475 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa"
I20260812 06:37:27.740728 27475 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 3 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 05c5ce1d7235466e8411a134225d94f8 in term 3.
I20260812 06:37:27.741190 27473 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "05c5ce1d7235466e8411a134225d94f8" candidate_term: 3 candidate_status { last_received { term: 2 index: 15 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa"
I20260812 06:37:27.741335 27473 raft_consensus.cc:2376] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 3 FOLLOWER]: Leader election vote request: Already granted yes vote for candidate 05c5ce1d7235466e8411a134225d94f8 in term 3. Re-sending same reply.
W20260812 06:37:28.158641 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:28.592821 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:29.039312 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
I20260812 06:37:29.227705 28093 raft_consensus.cc:493] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 3 FOLLOWER]: Starting pre-election (detected failure of leader 05c5ce1d7235466e8411a134225d94f8)
I20260812 06:37:29.227933 28093 raft_consensus.cc:515] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 3 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:29.228588 28093 leader_election.cc:290] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [CANDIDATE]: Term 4 pre-election: Requested pre-vote from peers 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791), 05c5ce1d7235466e8411a134225d94f8 (127.25.200.252:42861)
I20260812 06:37:29.229174 27473 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "bfb892b2fa954f8ea24cbca371deef08" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa" is_pre_election: true
I20260812 06:37:29.229360 27473 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 3 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate bfb892b2fa954f8ea24cbca371deef08 in term 3.
I20260812 06:37:29.229811 27314 leader_election.cc:304] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: 6100ede5793b446b8760c1f085488cfa, bfb892b2fa954f8ea24cbca371deef08; no voters: 
I20260812 06:37:29.230033 28093 raft_consensus.cc:2804] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 3 FOLLOWER]: Leader pre-election won for term 4
I20260812 06:37:29.230108 28093 raft_consensus.cc:493] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 3 FOLLOWER]: Starting leader election (detected failure of leader 05c5ce1d7235466e8411a134225d94f8)
I20260812 06:37:29.230141 28093 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 3 FOLLOWER]: Advancing to term 4
I20260812 06:37:29.231155 28093 raft_consensus.cc:515] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 4 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:29.231606 28093 leader_election.cc:290] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [CANDIDATE]: Term 4 election: Requested vote from peers 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791), 05c5ce1d7235466e8411a134225d94f8 (127.25.200.252:42861)
I20260812 06:37:29.232059 27473 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "bfb892b2fa954f8ea24cbca371deef08" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "6100ede5793b446b8760c1f085488cfa"
I20260812 06:37:29.232247 27473 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 3 FOLLOWER]: Advancing to term 4
I20260812 06:37:29.233481 27473 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 4 FOLLOWER]: Leader election vote request: Granting yes vote for candidate bfb892b2fa954f8ea24cbca371deef08 in term 4.
I20260812 06:37:29.233952 27314 leader_election.cc:304] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: 6100ede5793b446b8760c1f085488cfa, bfb892b2fa954f8ea24cbca371deef08; no voters: 
I20260812 06:37:29.234134 28093 raft_consensus.cc:2804] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 4 FOLLOWER]: Leader election won for term 4
I20260812 06:37:29.234316 28093 raft_consensus.cc:697] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 4 LEADER]: Becoming Leader. State: Replica: bfb892b2fa954f8ea24cbca371deef08, State: Running, Role: LEADER
I20260812 06:37:29.234696 28093 consensus_queue.cc:237] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:29.236006 28095 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: New leader bfb892b2fa954f8ea24cbca371deef08. Latest consensus state: current_term: 4 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:29.236130 28095 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:29.236475 28097 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:29.238190 28097 catalog_manager.cc:679] Loaded metadata for table second_table [id=57cdb17a5a684f798489fa9488112bf6]
I20260812 06:37:29.238516 28097 catalog_manager.cc:679] Loaded metadata for table table-0 [id=c0e9c1c88e8344e6b84f3ce6897fed29]
I20260812 06:37:29.238758 28097 catalog_manager.cc:679] Loaded metadata for table table-1 [id=c61ee077d6f34cf3954bda8db06b369b]
I20260812 06:37:29.239013 28097 catalog_manager.cc:679] Loaded metadata for table test [id=cccdb7513d80485390c14b18d69dc055]
I20260812 06:37:29.240005 28097 tablet_loader.cc:96] loaded metadata for tablet 4e4ebb4952354aeb9f085d05346b1cc4 (table second_table [id=57cdb17a5a684f798489fa9488112bf6])
I20260812 06:37:29.240257 28097 tablet_loader.cc:96] loaded metadata for tablet 4f80a62e17a041f9a518659a72f158df (table table-0 [id=c0e9c1c88e8344e6b84f3ce6897fed29])
I20260812 06:37:29.240473 28097 tablet_loader.cc:96] loaded metadata for tablet 87f8e3e6f5844329a455cce463bfdcde (table test [id=cccdb7513d80485390c14b18d69dc055])
I20260812 06:37:29.240667 28097 tablet_loader.cc:96] loaded metadata for tablet a5fcc74d865848cbb0391ba4e022c662 (table table-1 [id=c61ee077d6f34cf3954bda8db06b369b])
I20260812 06:37:29.240800 28097 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:29.241273 28097 catalog_manager.cc:1295] Loaded cluster ID: ef0232db57374881948b4e55e70bb43f
I20260812 06:37:29.241322 28097 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:29.241906 28097 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:29.242326 28097 catalog_manager.cc:6103] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08: Loaded TSK: 0
I20260812 06:37:29.242964 28097 catalog_manager.cc:1550] Initializing in-progress tserver states...
W20260812 06:37:29.604912 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
I20260812 06:37:29.819002 27473 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [term 4 FOLLOWER]: Refusing update from remote peer bfb892b2fa954f8ea24cbca371deef08: Log matching property violated. Preceding OpId in replica: term: 2 index: 15. Preceding OpId from leader: term: 4 index: 20. (index mismatch)
I20260812 06:37:29.819727 28098 consensus_queue.cc:1048] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [LEADER]: Connected to new peer: Peer: permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 20, Last known committed idx: 15, Time since last communication: 0.000s
I20260812 06:37:29.822700 28103 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 4 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:29.822867 28103 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:29.823146 28099 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: SysCatalogTable state changed. Reason: New leader bfb892b2fa954f8ea24cbca371deef08. Latest consensus state: current_term: 4 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:29.823323 28099 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:29.823998 28108 sys_catalog.cc:455] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 4 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:29.824318 28099 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 4 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:29.824440 28099 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6100ede5793b446b8760c1f085488cfa [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:29.824218 28108 sys_catalog.cc:458] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [sys.catalog]: This master's current role is: LEADER
W20260812 06:37:30.012297 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:30.445235 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
I20260812 06:37:30.729938 27914 heartbeater.cc:499] Master 127.25.200.254:37995 was elected leader, sending a full tablet report...
W20260812 06:37:30.913975 27318 proxy.cc:239] Call had error, refreshing address and retrying: Timed out: RequestConsensusVote RPC to 127.25.200.252:42861 timed out after 1.684s (SENT)
W20260812 06:37:31.032006 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:31.549182 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:32.060990 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:32.547515 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:32.600088 27318 leader_election.cc:336] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [CANDIDATE]: Term 4 pre-election: RPC error from VoteRequest() call to peer 05c5ce1d7235466e8411a134225d94f8 (127.25.200.252:42861): Timed out: RequestConsensusVote RPC to 127.25.200.252:42861 timed out after 1.684s (SENT)
W20260812 06:37:32.950356 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:33.008471 27318 leader_election.cc:336] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [CANDIDATE]: Term 4 election: RPC error from VoteRequest() call to peer 05c5ce1d7235466e8411a134225d94f8 (127.25.200.252:42861): Timed out: RequestConsensusVote RPC to 127.25.200.252:42861 timed out after 1.887s (SENT)
W20260812 06:37:33.365043 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:33.863817 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:34.295192 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:34.720422 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:35.208876 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:35.684582 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:36.104590 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:36.699297 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:37.110942 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:37.630072 27445 consensus_peers.cc:183] SignalRequest failed: Illegal state: peer closed
W20260812 06:37:37.745465 27955 master_proxy_rpc.cc:203] Re-attempting GetTableSchema request to leader Master (127.25.200.252:42861)
I20260812 06:37:37.760303 27342 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:37420:
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 06:37:37.783705 27806 tablet_service.cc:1511] Processing CreateTablet for tablet 91b54503a7494287a87e2222e92b659f (DEFAULT_TABLE table=table-2 [id=64f8a7f407534ed7a54e14b8714d12bc]), partition=RANGE (key) PARTITION UNBOUNDED
I20260812 06:37:37.784202 27806 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 91b54503a7494287a87e2222e92b659f. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:37:37.786312 28154 tablet_bootstrap.cc:492] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4: Bootstrap starting.
I20260812 06:37:37.787437 28154 tablet_bootstrap.cc:654] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:37.788528 28154 tablet_bootstrap.cc:492] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4: No bootstrap required, opened a new log
I20260812 06:37:37.788604 28154 ts_tablet_manager.cc:1403] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:37.789023 28154 raft_consensus.cc:359] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:37.789124 28154 raft_consensus.cc:385] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:37.789146 28154 raft_consensus.cc:740] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Initialized, Role: FOLLOWER
I20260812 06:37:37.789254 28154 consensus_queue.cc:260] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:37.789321 28154 raft_consensus.cc:399] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:37:37.789345 28154 raft_consensus.cc:493] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:37:37.789373 28154 raft_consensus.cc:3060] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:37.790076 28154 raft_consensus.cc:515] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:37.790199 28154 leader_election.cc:304] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [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: fdc3a862e93348f494337b3c43cc4cb4; no voters: 
I20260812 06:37:37.790335 28154 leader_election.cc:290] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:37:37.790474 28156 raft_consensus.cc:2804] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:37.790928 28154 ts_tablet_manager.cc:1434] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:37.791152 28156 raft_consensus.cc:697] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [term 1 LEADER]: Becoming Leader. State: Replica: fdc3a862e93348f494337b3c43cc4cb4, State: Running, Role: LEADER
I20260812 06:37:37.791347 28156 consensus_queue.cc:237] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 [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: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } }
I20260812 06:37:37.793226 27342 catalog_manager.cc:5719] T 91b54503a7494287a87e2222e92b659f P fdc3a862e93348f494337b3c43cc4cb4 reported cstate change: term changed from 0 to 1, leader changed from <none> to fdc3a862e93348f494337b3c43cc4cb4 (127.0.0.1). New cstate: current_term: 1 leader_uuid: "fdc3a862e93348f494337b3c43cc4cb4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fdc3a862e93348f494337b3c43cc4cb4" member_type: VOTER last_known_addr { host: "127.0.0.1" port: 37265 } health_report { overall_health: HEALTHY } } }
W20260812 06:37:37.803200 27588 leader_election.cc:336] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [CANDIDATE]: Term 3 election: RPC error from VoteRequest() call to peer 6100ede5793b446b8760c1f085488cfa (127.25.200.253:46791): Timed out: RequestConsensusVote RPC to 127.25.200.253:46791 timed out after 1.949s (SENT)
I20260812 06:37:37.803560 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 27711
W20260812 06:37:37.803541 27588 connection.cc:256] RPC call timeout handler was delayed by -8.08892705803737s: this may be due to a process-wide pause such as swapping, logging-related delays, or allocator lock contention. Will allow extra 0.3065s for a response
I20260812 06:37:37.806039 27618 raft_consensus.cc:1240] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 3 LEADER]: Rejecting Update request from peer 6100ede5793b446b8760c1f085488cfa for earlier term 2. Current term is 3. Ops: []
I20260812 06:37:37.806273 27620 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "bfb892b2fa954f8ea24cbca371deef08" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "05c5ce1d7235466e8411a134225d94f8"
I20260812 06:37:37.806561 27621 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "bfb892b2fa954f8ea24cbca371deef08" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "05c5ce1d7235466e8411a134225d94f8" is_pre_election: true
I20260812 06:37:37.806797 27622 raft_consensus.cc:3055] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 3 LEADER]: Stepping down as leader of term 3
I20260812 06:37:37.806845 27622 raft_consensus.cc:740] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 3 LEADER]: Becoming Follower/Learner. State: Replica: 05c5ce1d7235466e8411a134225d94f8, State: Running, Role: LEADER
I20260812 06:37:37.807017 27622 consensus_queue.cc:260] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [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: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } }
I20260812 06:37:37.807266 27622 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 3 FOLLOWER]: Advancing to term 4
I20260812 06:37:37.808573 27623 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "bfb892b2fa954f8ea24cbca371deef08" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "05c5ce1d7235466e8411a134225d94f8" is_pre_election: true
I20260812 06:37:37.808825 27625 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "bfb892b2fa954f8ea24cbca371deef08" candidate_term: 4 candidate_status { last_received { term: 3 index: 19 } } ignore_live_leader: false dest_uuid: "05c5ce1d7235466e8411a134225d94f8"
I20260812 06:37:37.809998 27535 master_runner.cc:233] Existing masters have differing terms: 2 vs 4
I20260812 06:37:37.810113 27535 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
I20260812 06:37:37.813283 27361 raft_consensus.cc:1240] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [term 4 LEADER]: Rejecting Update request from peer 05c5ce1d7235466e8411a134225d94f8 for earlier term 3. Current term is 4. Ops: []
I20260812 06:37:37.814322 27622 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [term 4 FOLLOWER]: Refusing update from remote peer bfb892b2fa954f8ea24cbca371deef08: Log matching property violated. Preceding OpId in replica: term: 3 index: 19. Preceding OpId from leader: term: 4 index: 20. (index mismatch)
I20260812 06:37:37.815289 28119 consensus_queue.cc:1048] T 00000000000000000000000000000000 P bfb892b2fa954f8ea24cbca371deef08 [LEADER]: Connected to new peer: Peer: permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 20, Last known committed idx: 19, Time since last communication: 0.000s
I20260812 06:37:37.815328 27406 master_runner.cc:233] Existing masters have differing terms: 3 vs 4
I20260812 06:37:37.815444 27406 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:37.815833 27590 connection.cc:466] server connection from 127.0.0.1:51450 torn down before Call kudu.master.MasterService.GetTableSchema from 127.0.0.1:51450 (request call id 3) could send its response
I20260812 06:37:37.817734 28070 sys_catalog.cc:455] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 4 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:37.817878 28070 sys_catalog.cc:458] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:37.817924 27294 master_runner.cc:233] Existing masters have differing terms: 3 vs 4
I20260812 06:37:37.818018 27294 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
I20260812 06:37:37.818141 28046 sys_catalog.cc:455] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: SysCatalogTable state changed. Reason: New leader bfb892b2fa954f8ea24cbca371deef08. Latest consensus state: current_term: 4 leader_uuid: "bfb892b2fa954f8ea24cbca371deef08" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfb892b2fa954f8ea24cbca371deef08" member_type: VOTER last_known_addr { host: "127.25.200.254" port: 37995 } } peers { permanent_uuid: "6100ede5793b446b8760c1f085488cfa" member_type: VOTER last_known_addr { host: "127.25.200.253" port: 46791 } } peers { permanent_uuid: "05c5ce1d7235466e8411a134225d94f8" member_type: VOTER last_known_addr { host: "127.25.200.252" port: 42861 } } }
I20260812 06:37:37.818248 28046 sys_catalog.cc:458] T 00000000000000000000000000000000 P 05c5ce1d7235466e8411a134225d94f8 [sys.catalog]: This master's current role is: FOLLOWER
W20260812 06:37:37.826210 27586 connection.cc:573] server connection from 127.0.0.1:51967 recv error: Network error: recv error from unknown peer: Transport endpoint is not connected (error 107)
I20260812 06:37:37.826535 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 27294
I20260812 06:37:37.843298 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 27406
I20260812 06:37:37.855366 26403 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task0Fisrt/build/debug/bin/kudu with pid 27535
2026-08-12T06:37:37Z chronyd exiting
[       OK ] MasterMigrationTest.TestEndToEndMigration (24260 ms)
[----------] 1 test from MasterMigrationTest (24260 ms total)

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