[==========] Running 17 tests from 3 test suites.
[----------] Global test environment set-up.
[----------] 2 tests from MockHybridClockTest
[ RUN      ] MockHybridClockTest.TestMockedSystemClock
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:37:33.323688   804 hybrid_clock.cc:584] initializing the hybrid clock with 'mock' time source
I20260812 06:37:33.323849   804 hybrid_clock.cc:648] HybridClock initialized: now 0 us; error 0 us; skew 500 ppm
[       OK ] MockHybridClockTest.TestMockedSystemClock (3 ms)
[ RUN      ] MockHybridClockTest.TestClockDealsWithWrapping
I20260812 06:37:33.325824   804 hybrid_clock.cc:584] initializing the hybrid clock with 'mock' time source
I20260812 06:37:33.325883   804 hybrid_clock.cc:648] HybridClock initialized: now 0 us; error 0 us; skew 500 ppm
[       OK ] MockHybridClockTest.TestClockDealsWithWrapping (9 ms)
[----------] 2 tests from MockHybridClockTest (13 ms total)

[----------] 13 tests from HybridClockTest
[ RUN      ] HybridClockTest.NowValuesIncreaseMonotonically
I20260812 06:37:33.335714   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:33.335772   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:33.335798   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516653335797 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.NowValuesIncreaseMonotonically (1 ms)
[ RUN      ] HybridClockTest.UpdateLogicalValueIncreasesByAmount
I20260812 06:37:33.336647   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:33.336686   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:33.336699   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516653336699 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.UpdateLogicalValueIncreasesByAmount (0 ms)
[ RUN      ] HybridClockTest.WaitUntilAfterCase1
I20260812 06:37:33.337482   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:33.337519   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:33.337545   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516653337545 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.WaitUntilAfterCase1 (0 ms)
[ RUN      ] HybridClockTest.WaitUntilAfterCase2
I20260812 06:37:33.338272   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:33.338308   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:33.338321   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516653338321 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.WaitUntilAfterCase2 (2 ms)
[ RUN      ] HybridClockTest.TestIsAfter
I20260812 06:37:33.341210   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:33.341246   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:33.341259   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516653341260 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.TestIsAfter (0 ms)
[ RUN      ] HybridClockTest.TestClockDoesntGoBackwardsWithUpdates
I20260812 06:37:33.341965   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:33.342001   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:33.342013   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516653342014 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.TestClockDoesntGoBackwardsWithUpdates (1001 ms)
[ RUN      ] HybridClockTest.TestGetPhysicalComponentDifference
I20260812 06:37:34.343644   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:34.343700   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:34.343715   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516654343715 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.TestGetPhysicalComponentDifference (2 ms)
[ RUN      ] HybridClockTest.TestRideOverNtpInterruption
I20260812 06:37:34.345759   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:34.345803   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:34.345817   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516654345817 us; error 0 us; skew 500 ppm
E20260812 06:37:35.346094   804 hybrid_clock.cc:750] unable to read clock for last 1.000s: Service unavailable: Injected clock unsync error
[       OK ] HybridClockTest.TestRideOverNtpInterruption (1001 ms)
[ RUN      ] HybridClockTest.SlowClockInitialisation
I20260812 06:37:35.347715   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:35.347764   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:35.347779   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516655347779 us; error 0 us; skew 500 ppm
2026-08-12T06:37:35Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
2026-08-12T06:37:35Z Disabled control of system clock
I20260812 06:37:35.363536   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.62:41113
--webserver_interface=127.0.201.62
--webserver_port=0
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
W20260812 06:37:35.477039   824 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:35.477299   824 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:35.477330   824 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:35.477393   824 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:35.480937   824 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:35.481021   824 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:35.481041   824 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:35.481058   824 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:35.481083   824 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:35.485302   824 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.62:41113
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.0.201.62
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.824
--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:35.486521   824 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:35.487754   824 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:35.490947   824 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:35.491307   824 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:35.491515   824 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:35.593207   824 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:35.594406   824 hybrid_clock.cc:648] HybridClock initialized: now 1786516655594353 us; error 86 us; skew 500 ppm
I20260812 06:37:35.596557   824 webserver.cc:533] Webserver started at http://127.0.201.62:35649/ using document root <none> and password file <none>
I20260812 06:37:35.597219   824 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:35.597285   824 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:35.597509   824 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:35.599244   824 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data/instance:
uuid: "aab405a221a442abaaba95e3dc5eac7c"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:35.599743   824 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal/instance:
uuid: "aab405a221a442abaaba95e3dc5eac7c"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:35.603639   824 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.002s
I20260812 06:37:35.606310   837 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:35.607596   824 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.000s
I20260812 06:37:35.607761   824 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
uuid: "aab405a221a442abaaba95e3dc5eac7c"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:35.607877   824 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:35.632517   824 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:35.633296   824 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:35.633487   824 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:35.641265   824 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.62:41113
I20260812 06:37:35.641325   956 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.62:41113 every 8 connection(s)
I20260812 06:37:35.642375   824 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:35.645444   960 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:35.648494   960 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } has no permanent_uuid. Determining permanent_uuid...
I20260812 06:37:35.652150   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 824
I20260812 06:37:35.652297   804 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal/instance
I20260812 06:37:35.653913   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.61:32975
--webserver_interface=127.0.201.61
--webserver_port=0
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:35.664006   960 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } has no permanent_uuid. Determining permanent_uuid...
W20260812 06:37:35.664978   861 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.0.201.61:32975: connect: Connection refused (error 111)
W20260812 06:37:35.665908   960 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.61:32975: Network error: Client connection negotiation failed: client connection to 127.0.201.61:32975: connect: Connection refused (error 111)
I20260812 06:37:35.715143   960 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } attempt: 1
W20260812 06:37:35.716741   960 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.61:32975: Network error: Client connection negotiation failed: client connection to 127.0.201.61:32975: connect: Connection refused (error 111)
W20260812 06:37:35.769626   968 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:35.769939   968 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:35.769990   968 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:35.770058   968 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:35.774020   968 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:35.774122   968 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:35.774156   968 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:35.774180   968 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:35.774204   968 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:35.778870   968 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.61:32975
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data/info.pb
--webserver_interface=127.0.201.61
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.968
--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:35.780217   968 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:35.781545   968 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:35.784969   960 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } attempt: 2
I20260812 06:37:35.785593   968 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:35.786023   968 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:35.786221   968 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
W20260812 06:37:35.786604   960 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.61:32975: Network error: Client connection negotiation failed: client connection to 127.0.201.61:32975: connect: Connection refused (error 111)
I20260812 06:37:35.877806   960 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } attempt: 3
W20260812 06:37:35.879619   960 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.61:32975: Network error: Client connection negotiation failed: client connection to 127.0.201.61:32975: connect: Connection refused (error 111)
I20260812 06:37:35.887918   968 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:35.889189   968 hybrid_clock.cc:648] HybridClock initialized: now 1786516655889173 us; error 38 us; skew 500 ppm
I20260812 06:37:35.891453   968 webserver.cc:533] Webserver started at http://127.0.201.61:42431/ using document root <none> and password file <none>
I20260812 06:37:35.892060   968 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:35.892123   968 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:35.892324   968 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:35.894002   968 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data/instance:
uuid: "bf0f8ab4356f4b729715fc4606c0022f"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:35.894472   968 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal/instance:
uuid: "bf0f8ab4356f4b729715fc4606c0022f"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:35.897986   968 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:37:35.900288   986 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:35.901423   968 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:35.901590   968 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
uuid: "bf0f8ab4356f4b729715fc4606c0022f"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:35.901692   968 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:35.921669   968 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:35.922432   968 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:35.922616   968 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:35.930455   968 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.61:32975
I20260812 06:37:35.930452  1071 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.61:32975 every 8 connection(s)
I20260812 06:37:35.931531   968 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data/info.pb
I20260812 06:37:35.933171   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 968
I20260812 06:37:35.933280   804 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal/instance
I20260812 06:37:35.934021   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.60:44735
--webserver_interface=127.0.201.60
--webserver_port=0
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:35.935097  1073 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:35.938380  1073 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } has no permanent_uuid. Determining permanent_uuid...
I20260812 06:37:35.948499  1073 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } has no permanent_uuid. Determining permanent_uuid...
I20260812 06:37:35.954739  1073 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } has no permanent_uuid. Determining permanent_uuid...
W20260812 06:37:35.955538   987 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
W20260812 06:37:35.956481  1073 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
I20260812 06:37:36.005694  1073 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } attempt: 1
W20260812 06:37:36.007292  1073 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
I20260812 06:37:36.022817   960 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } attempt: 4
I20260812 06:37:36.027426   960 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } has no permanent_uuid. Determining permanent_uuid...
W20260812 06:37:36.028867   960 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
W20260812 06:37:36.049746  1076 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:36.050019  1076 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:36.050061  1076 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:36.050125  1076 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:36.053603  1076 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:36.053673  1076 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:36.053700  1076 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:36.053723  1076 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:36.053756  1076 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:36.057776  1076 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.60:44735
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data/info.pb
--webserver_interface=127.0.201.60
--webserver_port=0
--never_fsync=true
--heap_profile_path=/tmp/kudu.1076
--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:36.058991  1076 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:36.060169  1076 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:36.063601  1076 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:36.063977  1076 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:36.064129  1076 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:36.075500  1073 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } attempt: 2
W20260812 06:37:36.077183  1073 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
I20260812 06:37:36.088114   960 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } attempt: 1
W20260812 06:37:36.089752   960 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
I20260812 06:37:36.156981   960 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } attempt: 2
W20260812 06:37:36.158703   960 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
I20260812 06:37:36.166880  1076 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:36.168179  1076 hybrid_clock.cc:648] HybridClock initialized: now 1786516656168109 us; error 86 us; skew 500 ppm
I20260812 06:37:36.168378  1073 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } attempt: 3
W20260812 06:37:36.170203  1073 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111)
I20260812 06:37:36.171412  1076 webserver.cc:533] Webserver started at http://127.0.201.60:38759/ using document root <none> and password file <none>
I20260812 06:37:36.172152  1076 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:36.172230  1076 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:36.172468  1076 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:36.174492  1076 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data/instance:
uuid: "a9242b59964e463a8041ddc8a472ba7d"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:36.175062  1076 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal/instance:
uuid: "a9242b59964e463a8041ddc8a472ba7d"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:36.179044  1076 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.005s	sys 0.000s
I20260812 06:37:36.181784  1099 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:36.183099  1076 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:37:36.183266  1076 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
uuid: "a9242b59964e463a8041ddc8a472ba7d"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:36.183391  1076 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:36.195329  1076 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:36.196074  1076 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:36.196249  1076 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:36.204247  1191 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.60:44735 every 8 connection(s)
I20260812 06:37:36.204245  1076 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.60:44735
I20260812 06:37:36.205485  1076 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data/info.pb
I20260812 06:37:36.208833  1194 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:36.212366  1194 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } has no permanent_uuid. Determining permanent_uuid...
I20260812 06:37:36.212654   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 1076
I20260812 06:37:36.212805   804 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal/instance
I20260812 06:37:36.221953  1194 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } has no permanent_uuid. Determining permanent_uuid...
I20260812 06:37:36.227218  1194 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } has no permanent_uuid. Determining permanent_uuid...
I20260812 06:37:36.234294  1194 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Bootstrap starting.
I20260812 06:37:36.236574  1194 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:36.237428  1194 log.cc:826] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:36.239212  1194 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: No bootstrap required, opened a new log
I20260812 06:37:36.242573  1194 raft_consensus.cc:359] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.242761  1194 raft_consensus.cc:385] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:36.242798  1194 raft_consensus.cc:740] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a9242b59964e463a8041ddc8a472ba7d, State: Initialized, Role: FOLLOWER
I20260812 06:37:36.243203  1194 consensus_queue.cc:260] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.243691  1215 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.243813  1215 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:36.244335  1194 sys_catalog.cc:565] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: configured and running, proceeding with master startup.
W20260812 06:37:36.249781  1230 catalog_manager.cc:1594] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:37:36.249861  1230 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:37:36.247852  1076 master_runner.cc:187] Error getting master registration for 127.0.201.62:41113: OK, instance_id { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" instance_seqno: 1786516655639116 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
I20260812 06:37:36.250075  1076 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
I20260812 06:37:36.258879   960 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } attempt: 3
I20260812 06:37:36.265326   960 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Bootstrap starting.
I20260812 06:37:36.267714   960 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:36.268496   960 log.cc:826] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:36.270772   960 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: No bootstrap required, opened a new log
I20260812 06:37:36.274307   960 raft_consensus.cc:359] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.274499   960 raft_consensus.cc:385] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:36.274537   960 raft_consensus.cc:740] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: aab405a221a442abaaba95e3dc5eac7c, State: Initialized, Role: FOLLOWER
I20260812 06:37:36.274972   960 consensus_queue.cc:260] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.275499  1233 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.275631  1233 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:36.276293   960 sys_catalog.cc:565] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:36.281601   824 master_runner.cc:187] Error getting master registration for 127.0.201.61:32975: OK, instance_id { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" instance_seqno: 1786516655928349 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
I20260812 06:37:36.283881   824 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:36.284230  1253 catalog_manager.cc:1594] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:37:36.284318  1253 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:37:36.313380  1073 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } attempt: 4
I20260812 06:37:36.321118  1073 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Bootstrap starting.
I20260812 06:37:36.324698  1073 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Neither blocks nor log segments found. Creating new log.
I20260812 06:37:36.325803  1073 log.cc:826] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:36.328790  1073 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: No bootstrap required, opened a new log
I20260812 06:37:36.333132  1073 raft_consensus.cc:359] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.333379  1073 raft_consensus.cc:385] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:37:36.333496  1073 raft_consensus.cc:740] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: bf0f8ab4356f4b729715fc4606c0022f, State: Initialized, Role: FOLLOWER
I20260812 06:37:36.334043  1073 consensus_queue.cc:260] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.334677  1263 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.334877  1263 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:36.335520  1073 sys_catalog.cc:565] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: configured and running, proceeding with master startup.
W20260812 06:37:36.343410  1277 catalog_manager.cc:1594] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:37:36.343595  1277 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:37:36.346122   968 master_runner.cc:264] No leader master found from master bf0f8ab4356f4b729715fc4606c0022f
I20260812 06:37:36.346222   968 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
I20260812 06:37:36.373088  1233 raft_consensus.cc:493] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:36.373345  1233 raft_consensus.cc:515] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.374636  1233 leader_election.cc:290] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers bf0f8ab4356f4b729715fc4606c0022f (127.0.201.61:32975), a9242b59964e463a8041ddc8a472ba7d (127.0.201.60:44735)
I20260812 06:37:36.375739  1033 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "bf0f8ab4356f4b729715fc4606c0022f" is_pre_election: true
I20260812 06:37:36.376111  1033 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 0.
I20260812 06:37:36.377103   861 leader_election.cc:304] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 1 pre-election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: aab405a221a442abaaba95e3dc5eac7c, bf0f8ab4356f4b729715fc4606c0022f; no voters: 
I20260812 06:37:36.377472  1149 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a9242b59964e463a8041ddc8a472ba7d" is_pre_election: true
I20260812 06:37:36.377805  1149 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 0.
I20260812 06:37:36.378633  1233 raft_consensus.cc:2804] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Leader pre-election won for term 1
I20260812 06:37:36.378713  1233 raft_consensus.cc:493] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:36.378755  1233 raft_consensus.cc:3060] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:36.379940  1233 raft_consensus.cc:515] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.380363  1233 leader_election.cc:290] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 1 election: Requested vote from peers bf0f8ab4356f4b729715fc4606c0022f (127.0.201.61:32975), a9242b59964e463a8041ddc8a472ba7d (127.0.201.60:44735)
I20260812 06:37:36.381269  1033 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "bf0f8ab4356f4b729715fc4606c0022f"
I20260812 06:37:36.381405  1033 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:36.382611  1033 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 1.
I20260812 06:37:36.383076   861 leader_election.cc:304] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: aab405a221a442abaaba95e3dc5eac7c, bf0f8ab4356f4b729715fc4606c0022f; no voters: 
I20260812 06:37:36.383371  1149 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a9242b59964e463a8041ddc8a472ba7d"
I20260812 06:37:36.383498  1149 raft_consensus.cc:3060] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:37:36.384644  1149 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 1.
W20260812 06:37:36.385205   865 tablet.cc:2406] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Can't schedule compaction. Clean time has not been advanced past its initial value.
I20260812 06:37:36.388499  1233 raft_consensus.cc:2804] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:37:36.388876  1233 raft_consensus.cc:697] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 LEADER]: Becoming Leader. State: Replica: aab405a221a442abaaba95e3dc5eac7c, State: Running, Role: LEADER
I20260812 06:37:36.389339  1233 consensus_queue.cc:237] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 1, Majority size: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:36.391307  1233 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: New leader aab405a221a442abaaba95e3dc5eac7c. Latest consensus state: current_term: 1 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.391472  1233 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:36.391921  1283 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:36.393055  1283 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:36.400244  1149 raft_consensus.cc:1275] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 1 FOLLOWER]: Refusing update from remote peer aab405a221a442abaaba95e3dc5eac7c: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
I20260812 06:37:36.401168  1233 consensus_queue.cc:1048] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [LEADER]: Connected to new peer: Peer: permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:36.406356  1215 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: New leader aab405a221a442abaaba95e3dc5eac7c. Latest consensus state: current_term: 1 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.406525  1215 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:36.410701  1033 raft_consensus.cc:1275] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 1 FOLLOWER]: Refusing update from remote peer aab405a221a442abaaba95e3dc5eac7c: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
I20260812 06:37:36.411434  1233 consensus_queue.cc:1048] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [LEADER]: Connected to new peer: Peer: permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
I20260812 06:37:36.416006  1263 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: New leader aab405a221a442abaaba95e3dc5eac7c. Latest consensus state: current_term: 1 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.416159  1263 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:36.421126  1280 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.421851  1280 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:36.422870  1263 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.422986  1263 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:36.423501  1288 mvcc.cc:204] Tried to move back new op lower bound from 7317572224606253056 to 7317572224574484480. Current Snapshot: MvccSnapshot[applied={T|T < 7317572224606253056}]
I20260812 06:37:36.426807  1295 mvcc.cc:204] Tried to move back new op lower bound from 7317572224606253056 to 7317572224574484480. Current Snapshot: MvccSnapshot[applied={T|T < 7317572224606253056}]
I20260812 06:37:36.430724  1283 catalog_manager.cc:1383] Generated new cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:36.430792  1283 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:36.437613  1233 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.437767  1233 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:36.438433  1215 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:36.438552  1215 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:36.445411  1290 mvcc.cc:204] Tried to move back new op lower bound from 7317572224606253056 to 7317572224574484480. Current Snapshot: MvccSnapshot[applied={T|T < 7317572224606253056}]
I20260812 06:37:36.466092  1283 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:37:36.467392  1283 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:36.480831  1283 catalog_manager.cc:6092] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Generated new TSK 0
I20260812 06:37:36.481715  1283 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:36.492786   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.201.1:0
--local_ip_for_outbound_sockets=127.0.201.1
--webserver_interface=127.0.201.1
--webserver_port=0
--tserver_master_addrs=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
W20260812 06:37:36.608462  1304 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:36.608758  1304 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:36.608783  1304 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:36.608835  1304 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:36.612731  1304 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:36.612826  1304 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
W20260812 06:37:36.612927  1304 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.201.1
I20260812 06:37:36.617251  1304 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.0.201.1:0
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.201.1
--webserver_port=0
--tserver_master_addrs=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--never_fsync=true
--heap_profile_path=/tmp/kudu.1304
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.0.201.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:36.618502  1304 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:36.619763  1304 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:36.624439  1304 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:36.624703  1304 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:36.624833  1304 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:36.725991  1304 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:36.727202  1304 hybrid_clock.cc:648] HybridClock initialized: now 1786516656727185 us; error 40 us; skew 500 ppm
I20260812 06:37:36.729565  1304 webserver.cc:533] Webserver started at http://127.0.201.1:37863/ using document root <none> and password file <none>
I20260812 06:37:36.730227  1304 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:36.730295  1304 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:36.730516  1304 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:37:36.732438  1304 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/instance:
uuid: "65129651abb24337ab27521ba563799e"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:36.733038  1304 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal/instance:
uuid: "65129651abb24337ab27521ba563799e"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:36.738158  1304 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.003s	sys 0.000s
I20260812 06:37:36.741726  1316 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:36.743429  1304 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:37:36.743808  1304 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
uuid: "65129651abb24337ab27521ba563799e"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:36.744009  1304 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:36.768265  1304 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:36.769014  1304 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:36.769173  1304 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:36.769790  1304 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:36.770928  1304 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:36.770979  1304 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:36.771023  1304 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:36.771036  1304 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:36.780557  1304 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.1:41877
I20260812 06:37:36.780640  1503 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.1:41877 every 8 connection(s)
I20260812 06:37:36.781579  1304 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:36.781738   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 1304
I20260812 06:37:36.781829   804 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal/instance
I20260812 06:37:36.791826  1504 heartbeater.cc:344] Connected to a master server at 127.0.201.60:44735
I20260812 06:37:36.791834  1506 heartbeater.cc:344] Connected to a master server at 127.0.201.61:32975
I20260812 06:37:36.791880  1505 heartbeater.cc:344] Connected to a master server at 127.0.201.62:41113
I20260812 06:37:36.792114  1504 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:36.792114  1505 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:36.792114  1506 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:36.792699  1505 heartbeater.cc:507] Master 127.0.201.62:41113 requested a full tablet report, sending...
I20260812 06:37:36.792701  1504 heartbeater.cc:507] Master 127.0.201.60:44735 requested a full tablet report, sending...
I20260812 06:37:36.792701  1506 heartbeater.cc:507] Master 127.0.201.61:32975 requested a full tablet report, sending...
I20260812 06:37:36.794147  1017 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:36.794159  1131 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:36.794169   888 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:36.795353   888 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.201.1:35647
I20260812 06:37:36.804809   804 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 06:37:36.805359   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 1304
I20260812 06:37:36.820093   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 824
I20260812 06:37:36.833344   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 968
I20260812 06:37:36.850884   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 1076
I20260812 06:37:36.862735  1520 webui_checker.cc:102] curl thread will poll the following URLs every 0.001s:
  http://127.0.201.1:37863/transactions
  http://127.0.201.60:38759/metrics
  http://127.0.201.1:37863/metrics
  http://127.0.201.62:35649/metrics
  http://127.0.201.61:42431/metrics
I20260812 06:37:36.862810   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.62:41113
--webserver_interface=127.0.201.62
--webserver_port=35649
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
W20260812 06:37:36.972259  1521 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:36.972505  1521 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:36.972548  1521 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:36.972615  1521 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:36.976009  1521 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:36.976078  1521 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:36.976104  1521 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:36.976126  1521 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:36.976164  1521 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:36.980067  1521 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.62:41113
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.0.201.62
--webserver_port=35649
--never_fsync=true
--heap_profile_path=/tmp/kudu.1521
--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:36.981207  1521 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:36.982364  1521 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:36.985443  1521 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:36.985814  1521 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:36.985960  1521 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:37.088181  1521 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:37.600600  1521 hybrid_clock.cc:648] HybridClock initialized: now 1786516657600504 us; error 224 us; skew 500 ppm
I20260812 06:37:37.603178  1521 webserver.cc:533] Webserver started at http://127.0.201.62:35649/ using document root <none> and password file <none>
I20260812 06:37:37.603935  1521 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:37.604040  1521 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:37.608119  1521 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:37:37.610535  1539 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:37.611857  1521 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.000s
I20260812 06:37:37.611985  1521 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
uuid: "aab405a221a442abaaba95e3dc5eac7c"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:37.612473  1521 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:37.629401  1521 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:37.630245  1521 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:37.630414  1521 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:37.639752  1521 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.62:41113
I20260812 06:37:37.639752  1635 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.62:41113 every 8 connection(s)
I20260812 06:37:37.641124  1521 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:37.645843  1637 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:37.647594  1637 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Bootstrap starting.
I20260812 06:37:37.649657   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 1521
I20260812 06:37:37.650039   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.61:32975
--webserver_interface=127.0.201.61
--webserver_port=42431
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:37.665593  1637 log.cc:826] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:37.672245  1637 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Bootstrap replayed 1/1 log segments. Stats: ops{read=4 overwritten=0 applied=4 ignored=0} inserts{seen=3 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 06:37:37.672662  1637 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Bootstrap complete.
I20260812 06:37:37.676661  1637 raft_consensus.cc:359] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:37.677104  1637 raft_consensus.cc:740] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: aab405a221a442abaaba95e3dc5eac7c, State: Initialized, Role: FOLLOWER
I20260812 06:37:37.677706  1637 consensus_queue.cc:260] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 4, Last appended: 1.4, Last appended by leader: 4, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:37.678193  1642 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:37.678377  1642 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:37.678952  1637 sys_catalog.cc:565] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:37.685029  1655 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:37.685201  1655 catalog_manager.cc:1588] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: loading cluster ID for follower catalog manager: success
I20260812 06:37:37.687772  1655 catalog_manager.cc:1610] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: acquiring CA information for follower catalog manager: success
I20260812 06:37:37.689349  1655 catalog_manager.cc:1638] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:37.695278  1521 master_runner.cc:187] Error getting master registration for 127.0.201.61:32975: Network error: Client connection negotiation failed: client connection to 127.0.201.61:32975: connect: Connection refused (error 111), 
I20260812 06:37:37.698093  1521 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:37.802551  1640 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:37.802896  1640 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:37.802945  1640 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:37.803009  1640 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:37.807873  1640 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:37.807973  1640 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:37.808004  1640 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:37.808029  1640 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:37.808063  1640 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:37.814079  1640 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.61:32975
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data/info.pb
--webserver_interface=127.0.201.61
--webserver_port=42431
--never_fsync=true
--heap_profile_path=/tmp/kudu.1640
--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:37.815658  1640 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:37.817207  1640 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:37.821470  1640 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:37.821933  1640 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:37.822109  1640 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:37.923784  1640 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:37.924963  1640 hybrid_clock.cc:648] HybridClock initialized: now 1786516657924947 us; error 42 us; skew 500 ppm
I20260812 06:37:37.927208  1640 webserver.cc:533] Webserver started at http://127.0.201.61:42431/ using document root <none> and password file <none>
I20260812 06:37:37.927871  1640 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:37.927976  1640 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:37.931967  1640 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:37:37.934077  1684 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:37.935209  1640 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:37:37.935320  1640 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
uuid: "bf0f8ab4356f4b729715fc4606c0022f"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:37.935752  1640 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:37.958036  1640 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:37.958773  1640 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:37.958957  1640 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:37.967787  1640 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.61:32975
I20260812 06:37:37.967777  1768 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.61:32975 every 8 connection(s)
I20260812 06:37:37.969362  1640 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data/info.pb
I20260812 06:37:37.974888  1769 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:37.977358  1769 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Bootstrap starting.
I20260812 06:37:37.978981   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 1640
I20260812 06:37:37.979358   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.60:44735
--webserver_interface=127.0.201.60
--webserver_port=38759
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:37.996271  1769 log.cc:826] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:38.003957  1769 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Bootstrap replayed 1/1 log segments. Stats: ops{read=4 overwritten=0 applied=4 ignored=0} inserts{seen=3 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 06:37:38.004402  1769 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Bootstrap complete.
I20260812 06:37:38.009125  1769 raft_consensus.cc:359] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:38.009546  1769 raft_consensus.cc:740] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: bf0f8ab4356f4b729715fc4606c0022f, State: Initialized, Role: FOLLOWER
I20260812 06:37:38.010109  1769 consensus_queue.cc:260] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 4, Last appended: 1.4, Last appended by leader: 4, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:38.010635  1774 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:38.010843  1774 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:38.011349  1769 sys_catalog.cc:565] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:38.017135  1794 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:38.017294  1794 catalog_manager.cc:1588] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: loading cluster ID for follower catalog manager: success
I20260812 06:37:38.019975  1794 catalog_manager.cc:1610] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: acquiring CA information for follower catalog manager: success
I20260812 06:37:38.021531  1794 catalog_manager.cc:1638] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:38.041455  1640 master_runner.cc:187] Error getting master registration for 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111), 
I20260812 06:37:38.043597  1640 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:38.127758  1772 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:38.128142  1772 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:38.128183  1772 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:38.128261  1772 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:38.134220  1772 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:38.134322  1772 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:38.134352  1772 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:38.134377  1772 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:38.134411  1772 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:38.140803  1772 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.60:44735
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data/info.pb
--webserver_interface=127.0.201.60
--webserver_port=38759
--never_fsync=true
--heap_profile_path=/tmp/kudu.1772
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:38.142223  1772 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:38.143785  1772 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:38.147588  1772 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:38.148025  1772 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:38.148196  1772 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:38.250082  1772 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:38.251307  1772 hybrid_clock.cc:648] HybridClock initialized: now 1786516658251270 us; error 66 us; skew 500 ppm
I20260812 06:37:38.254000  1772 webserver.cc:533] Webserver started at http://127.0.201.60:38759/ using document root <none> and password file <none>
I20260812 06:37:38.254782  1772 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:38.254884  1772 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:38.259425  1772 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.000s	sys 0.004s
I20260812 06:37:38.262054  1812 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.263322  1772 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.001s
I20260812 06:37:38.263468  1772 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
uuid: "a9242b59964e463a8041ddc8a472ba7d"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:38.263940  1772 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:38.281263  1772 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:38.282056  1772 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:38.282254  1772 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:38.291128  1772 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.60:44735
I20260812 06:37:38.291128  1897 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.60:44735 every 8 connection(s)
I20260812 06:37:38.292435  1772 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data/info.pb
I20260812 06:37:38.296775  1898 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:38.298756  1898 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Bootstrap starting.
I20260812 06:37:38.298794   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 1772
I20260812 06:37:38.299206   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.201.1:41877
--local_ip_for_outbound_sockets=127.0.201.1
--tserver_master_addrs=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--webserver_port=37863
--webserver_interface=127.0.201.1
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:38.318655  1898 log.cc:826] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:38.326813  1898 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Bootstrap replayed 1/1 log segments. Stats: ops{read=4 overwritten=0 applied=4 ignored=0} inserts{seen=3 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 06:37:38.327265  1898 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Bootstrap complete.
I20260812 06:37:38.332181  1898 raft_consensus.cc:359] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 1 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:38.332634  1898 raft_consensus.cc:740] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 1 FOLLOWER]: Becoming Follower/Learner. State: Replica: a9242b59964e463a8041ddc8a472ba7d, State: Initialized, Role: FOLLOWER
I20260812 06:37:38.333303  1898 consensus_queue.cc:260] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 4, Last appended: 1.4, Last appended by leader: 4, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:38.333873  1905 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:38.334198  1905 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:38.334709  1898 sys_catalog.cc:565] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:38.340533  1926 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:38.340615  1926 catalog_manager.cc:1588] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: loading cluster ID for follower catalog manager: success
I20260812 06:37:38.343137  1926 catalog_manager.cc:1610] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: acquiring CA information for follower catalog manager: success
I20260812 06:37:38.344646  1926 catalog_manager.cc:1638] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:38.369024  1772 master_runner.cc:264] No leader master found from master a9242b59964e463a8041ddc8a472ba7d
I20260812 06:37:38.369127  1772 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:38.438563  1901 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:38.438829  1901 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:38.438866  1901 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:38.438930  1901 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:38.442610  1901 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:38.442695  1901 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
W20260812 06:37:38.442781  1901 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.201.1
I20260812 06:37:38.446872  1901 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.0.201.1:41877
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.201.1
--webserver_port=37863
--tserver_master_addrs=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--never_fsync=true
--heap_profile_path=/tmp/kudu.1901
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.0.201.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:38.448024  1901 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:38.449221  1901 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:38.454320  1901 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:38.454592  1901 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:38.454748  1901 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:38.555963  1901 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:38.557179  1901 hybrid_clock.cc:648] HybridClock initialized: now 1786516658557157 us; error 49 us; skew 500 ppm
I20260812 06:37:38.559273  1901 webserver.cc:533] Webserver started at http://127.0.201.1:37863/ using document root <none> and password file <none>
I20260812 06:37:38.559882  1901 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:38.559979  1901 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:38.563750  1901 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:38.565748  1943 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.566864  1901 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:38.566975  1901 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
uuid: "65129651abb24337ab27521ba563799e"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:38.567371  1901 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:38.603135  1901 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:38.603857  1901 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:38.604046  1901 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:38.604713  1901 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:38.605834  1901 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:38.605890  1901 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.605948  1901 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:38.605978  1901 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:38.617041  1901 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.1:41877
I20260812 06:37:38.617071  2136 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.1:41877 every 8 connection(s)
I20260812 06:37:38.618207  1901 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:38.619611   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 1901
I20260812 06:37:38.630065  2138 heartbeater.cc:344] Connected to a master server at 127.0.201.62:41113
I20260812 06:37:38.630371  2138 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:38.631095  2138 heartbeater.cc:507] Master 127.0.201.62:41113 requested a full tablet report, sending...
I20260812 06:37:38.632984  2139 heartbeater.cc:344] Connected to a master server at 127.0.201.61:32975
I20260812 06:37:38.633101  2139 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:38.633330  2139 heartbeater.cc:507] Master 127.0.201.61:32975 requested a full tablet report, sending...
I20260812 06:37:38.633635  1564 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:38.635102  1713 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:38.637573  2137 heartbeater.cc:344] Connected to a master server at 127.0.201.60:44735
I20260812 06:37:38.637718  2137 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:38.637957  2137 heartbeater.cc:507] Master 127.0.201.60:44735 requested a full tablet report, sending...
I20260812 06:37:38.639271  1839 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:38.648342   804 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 06:37:39.392745  2149 raft_consensus.cc:493] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:39.393002  2149 raft_consensus.cc:515] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:39.394260  2149 leader_election.cc:290] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 2 pre-election: Requested pre-vote from peers bf0f8ab4356f4b729715fc4606c0022f (127.0.201.61:32975), a9242b59964e463a8041ddc8a472ba7d (127.0.201.60:44735)
I20260812 06:37:39.398634  1728 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 2 candidate_status { last_received { term: 1 index: 4 } } ignore_live_leader: false dest_uuid: "bf0f8ab4356f4b729715fc4606c0022f" is_pre_election: true
I20260812 06:37:39.398838  1857 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 2 candidate_status { last_received { term: 1 index: 4 } } ignore_live_leader: false dest_uuid: "a9242b59964e463a8041ddc8a472ba7d" is_pre_election: true
I20260812 06:37:39.399034  1728 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 1 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 1.
I20260812 06:37:39.399152  1857 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 1 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 1.
I20260812 06:37:39.399545  1543 leader_election.cc:304] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [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: aab405a221a442abaaba95e3dc5eac7c, bf0f8ab4356f4b729715fc4606c0022f; no voters: 
I20260812 06:37:39.399834  2149 raft_consensus.cc:2804] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Leader pre-election won for term 2
I20260812 06:37:39.399920  2149 raft_consensus.cc:493] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:39.399958  2149 raft_consensus.cc:3060] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 1 FOLLOWER]: Advancing to term 2
I20260812 06:37:39.402220  2149 raft_consensus.cc:515] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:39.402645  2149 leader_election.cc:290] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 2 election: Requested vote from peers bf0f8ab4356f4b729715fc4606c0022f (127.0.201.61:32975), a9242b59964e463a8041ddc8a472ba7d (127.0.201.60:44735)
I20260812 06:37:39.403082  1728 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 2 candidate_status { last_received { term: 1 index: 4 } } ignore_live_leader: false dest_uuid: "bf0f8ab4356f4b729715fc4606c0022f"
I20260812 06:37:39.403106  1857 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 2 candidate_status { last_received { term: 1 index: 4 } } ignore_live_leader: false dest_uuid: "a9242b59964e463a8041ddc8a472ba7d"
I20260812 06:37:39.403219  1728 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 1 FOLLOWER]: Advancing to term 2
I20260812 06:37:39.403239  1857 raft_consensus.cc:3060] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 1 FOLLOWER]: Advancing to term 2
I20260812 06:37:39.404999  1728 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 2 FOLLOWER]: Leader election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 2.
I20260812 06:37:39.405349  1543 leader_election.cc:304] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [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: aab405a221a442abaaba95e3dc5eac7c, bf0f8ab4356f4b729715fc4606c0022f; no voters: 
I20260812 06:37:39.405385  1857 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 2 FOLLOWER]: Leader election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 2.
I20260812 06:37:39.405550  2149 raft_consensus.cc:2804] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Leader election won for term 2
I20260812 06:37:39.405745  2149 raft_consensus.cc:697] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 LEADER]: Becoming Leader. State: Replica: aab405a221a442abaaba95e3dc5eac7c, State: Running, Role: LEADER
I20260812 06:37:39.406092  2149 consensus_queue.cc:237] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 4, Committed index: 4, Last appended: 1.4, Last appended by leader: 4, Current term: 2, Majority size: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:39.407727  2158 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: New leader aab405a221a442abaaba95e3dc5eac7c. Latest consensus state: current_term: 2 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:39.407848  2158 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:39.408150  2163 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:39.409183  2163 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:39.409554  2163 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:39.409603  2163 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:39.410117  2163 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:39.410486  2163 catalog_manager.cc:6103] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Loaded TSK: 0
I20260812 06:37:39.411029  2163 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:39.638695  1564 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.201.1:47839
I20260812 06:37:39.648694   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 1901
I20260812 06:37:39.668618   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 1521
I20260812 06:37:39.691098   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 1640
I20260812 06:37:39.707995   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 1772
I20260812 06:37:39.724716   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.62:41113
--webserver_interface=127.0.201.62
--webserver_port=35649
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
W20260812 06:37:39.849928  2164 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:39.850217  2164 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:39.850245  2164 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:39.850294  2164 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:39.853793  2164 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:39.853868  2164 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:39.853888  2164 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:39.853905  2164 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:39.853922  2164 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:39.858116  2164 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.62:41113
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data/info.pb
--webserver_interface=127.0.201.62
--webserver_port=35649
--never_fsync=true
--heap_profile_path=/tmp/kudu.2164
--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:39.859321  2164 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:39.860543  2164 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:39.863883  2164 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:39.864274  2164 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:39.864425  2164 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:39.966270  2164 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:39.967489  2164 hybrid_clock.cc:648] HybridClock initialized: now 1786516659967464 us; error 48 us; skew 500 ppm
I20260812 06:37:39.969858  2164 webserver.cc:533] Webserver started at http://127.0.201.62:35649/ using document root <none> and password file <none>
I20260812 06:37:39.970590  2164 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:39.970670  2164 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:39.974577  2164 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:37:39.976720  2184 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:39.977864  2164 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:39.978021  2164 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
uuid: "aab405a221a442abaaba95e3dc5eac7c"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:39.978438  2164 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:39.991979  2164 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:39.992769  2164 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:39.993001  2164 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:40.001361  2272 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.62:41113 every 8 connection(s)
I20260812 06:37:40.001374  2164 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.62:41113
I20260812 06:37:40.002774  2164 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-0/data/info.pb
I20260812 06:37:40.003108   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 2164
I20260812 06:37:40.003522   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.61:32975
--webserver_interface=127.0.201.61
--webserver_port=42431
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:40.008399  2273 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:40.011014  2273 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Bootstrap starting.
I20260812 06:37:40.031025  2273 log.cc:826] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:40.038862  2273 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Bootstrap replayed 1/1 log segments. Stats: ops{read=5 overwritten=0 applied=4 ignored=0} inserts{seen=3 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 1 replicates
I20260812 06:37:40.039357  2273 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Bootstrap complete.
I20260812 06:37:40.044188  2273 raft_consensus.cc:359] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Replica starting. Triggering 1 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:40.044925  2273 raft_consensus.cc:740] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Becoming Follower/Learner. State: Replica: aab405a221a442abaaba95e3dc5eac7c, State: Initialized, Role: FOLLOWER
I20260812 06:37:40.045534  2273 consensus_queue.cc:260] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 4, Last appended: 2.5, Last appended by leader: 5, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:40.046023  2279 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 2 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:40.046203  2279 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:40.046799  2273 sys_catalog.cc:565] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:40.052503  2293 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:40.052565  2293 catalog_manager.cc:1588] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: loading cluster ID for follower catalog manager: success
I20260812 06:37:40.054881  2293 catalog_manager.cc:1610] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: acquiring CA information for follower catalog manager: success
I20260812 06:37:40.056143  2293 catalog_manager.cc:1638] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:40.065837  2164 master_runner.cc:187] Error getting master registration for 127.0.201.61:32975: Network error: Client connection negotiation failed: client connection to 127.0.201.61:32975: connect: Connection refused (error 111), 
I20260812 06:37:40.067420  2164 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:40.163920  2275 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:40.164253  2275 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:40.164299  2275 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:40.164363  2275 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:40.169031  2275 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:40.169117  2275 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:40.169147  2275 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:40.169173  2275 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:40.169205  2275 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:40.175268  2275 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.61:32975
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data/info.pb
--webserver_interface=127.0.201.61
--webserver_port=42431
--never_fsync=true
--heap_profile_path=/tmp/kudu.2275
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:40.176617  2275 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:40.178308  2275 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:40.182639  2275 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:40.183121  2275 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:40.183282  2275 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:40.285288  2275 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:40.286548  2275 hybrid_clock.cc:648] HybridClock initialized: now 1786516660286532 us; error 41 us; skew 500 ppm
I20260812 06:37:40.289238  2275 webserver.cc:533] Webserver started at http://127.0.201.61:42431/ using document root <none> and password file <none>
I20260812 06:37:40.289995  2275 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:40.290076  2275 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:40.294842  2275 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.001s	sys 0.004s
I20260812 06:37:40.297544  2311 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.298939  2275 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:37:40.299091  2275 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
uuid: "bf0f8ab4356f4b729715fc4606c0022f"
format_stamp: "Formatted at 2026-08-12 06:37:35 on dist-test-slave-ncp9"
I20260812 06:37:40.299839  2275 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:40.313655  2275 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:40.314406  2275 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:40.314589  2275 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:40.322817  2275 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.61:32975
I20260812 06:37:40.322842  2419 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.61:32975 every 8 connection(s)
I20260812 06:37:40.324088  2275 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-1/data/info.pb
I20260812 06:37:40.324555   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 2275
I20260812 06:37:40.324992   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.0.201.60:44735
--webserver_interface=127.0.201.60
--webserver_port=38759
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--rpc_reuseport=true
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:40.328850  2423 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:40.331102  2423 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Bootstrap starting.
I20260812 06:37:40.349890  2423 log.cc:826] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:40.357403  2423 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Bootstrap replayed 1/1 log segments. Stats: ops{read=4 overwritten=0 applied=4 ignored=0} inserts{seen=3 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 06:37:40.357860  2423 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: Bootstrap complete.
I20260812 06:37:40.362665  2423 raft_consensus.cc:359] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 2 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:40.363113  2423 raft_consensus.cc:740] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 2 FOLLOWER]: Becoming Follower/Learner. State: Replica: bf0f8ab4356f4b729715fc4606c0022f, State: Initialized, Role: FOLLOWER
I20260812 06:37:40.363686  2423 consensus_queue.cc:260] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 4, Last appended: 1.4, Last appended by leader: 4, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:40.364173  2427 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 2 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:40.364369  2427 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:40.364858  2423 sys_catalog.cc:565] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:40.370432  2444 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:40.370522  2444 catalog_manager.cc:1588] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: loading cluster ID for follower catalog manager: success
I20260812 06:37:40.373183  2444 catalog_manager.cc:1610] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: acquiring CA information for follower catalog manager: success
I20260812 06:37:40.374687  2444 catalog_manager.cc:1638] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:40.389832  2275 master_runner.cc:187] Error getting master registration for 127.0.201.60:44735: Network error: Client connection negotiation failed: client connection to 127.0.201.60:44735: connect: Connection refused (error 111), 
I20260812 06:37:40.391916  2275 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:40.481163  2425 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:40.481544  2425 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:40.481595  2425 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:40.481664  2425 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:40.486750  2425 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
W20260812 06:37:40.486842  2425 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:40.486871  2425 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
W20260812 06:37:40.486897  2425 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
W20260812 06:37:40.486930  2425 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
I20260812 06:37:40.492771  2425 master_runner.cc:387] Master server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
--ipki_ca_key_size=768
--master_addresses=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--ipki_server_key_size=768
--openssl_security_level_override=0
--tsk_num_rsa_bits=512
--rpc_bind_addresses=127.0.201.60:44735
--rpc_reuseport=true
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data/info.pb
--webserver_interface=127.0.201.60
--webserver_port=38759
--never_fsync=true
--heap_profile_path=/tmp/kudu.2425
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false

Master server version:
kudu 1.19.0-SNAPSHOT
revision 70c51312f1186a8ae9f46647160b7730123f7601-dirty
build type DEBUG
built by None at 12 Aug 2026 06:06:26 UTC on 39be9dd7121a
I20260812 06:37:40.494262  2425 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:40.495734  2425 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:40.499784  2425 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:40.500214  2425 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:40.500385  2425 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:40.602099  2425 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:40.603312  2425 hybrid_clock.cc:648] HybridClock initialized: now 1786516660603300 us; error 43 us; skew 500 ppm
I20260812 06:37:40.605564  2425 webserver.cc:533] Webserver started at http://127.0.201.60:38759/ using document root <none> and password file <none>
I20260812 06:37:40.606283  2425 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:40.606354  2425 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:40.610275  2425 fs_manager.cc:714] Time spent opening directory manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:40.612242  2464 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.613363  2425 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:37:40.613477  2425 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
uuid: "a9242b59964e463a8041ddc8a472ba7d"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:40.613906  2425 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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:40.633347  2425 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:40.634097  2425 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:40.634277  2425 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:40.643035  2425 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.60:44735
I20260812 06:37:40.643030  2565 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.60:44735 every 8 connection(s)
I20260812 06:37:40.644256  2425 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/master-2/data/info.pb
I20260812 06:37:40.646206   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 2425
I20260812 06:37:40.646632   804 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskLic91R/build/debug/bin/kudu
/tmp/dist-test-taskLic91R/build/debug/bin/kudu
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/logs
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-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.201.1:41877
--local_ip_for_outbound_sockets=127.0.201.1
--tserver_master_addrs=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--webserver_port=37863
--webserver_interface=127.0.201.1
--builtin_ntp_servers=127.0.201.20:42405
--builtin_ntp_poll_interval_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--hybrid_clock_inject_init_delay_ms=100
--wall_clock_jump_detection=enabled with env {}
I20260812 06:37:40.649204  2566 sys_catalog.cc:263] Verifying existing consensus state
I20260812 06:37:40.651563  2566 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Bootstrap starting.
I20260812 06:37:40.670626  2566 log.cc:826] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Log is configured to *not* fsync() on all Append() calls
I20260812 06:37:40.678495  2566 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Bootstrap replayed 1/1 log segments. Stats: ops{read=4 overwritten=0 applied=4 ignored=0} inserts{seen=3 ignored=0} mutations{seen=0 ignored=0} orphaned_commits=0. Pending: 0 replicates
I20260812 06:37:40.678947  2566 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: Bootstrap complete.
I20260812 06:37:40.683887  2566 raft_consensus.cc:359] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 2 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:40.684353  2566 raft_consensus.cc:740] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 2 FOLLOWER]: Becoming Follower/Learner. State: Replica: a9242b59964e463a8041ddc8a472ba7d, State: Initialized, Role: FOLLOWER
I20260812 06:37:40.685017  2566 consensus_queue.cc:260] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 4, Last appended: 1.4, Last appended by leader: 4, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:40.686380  2566 sys_catalog.cc:565] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:37:40.689075  2575 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 2 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:40.689211  2575 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:40.692138  2590 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:40.692267  2590 catalog_manager.cc:1588] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: loading cluster ID for follower catalog manager: success
I20260812 06:37:40.695119  2590 catalog_manager.cc:1610] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: acquiring CA information for follower catalog manager: success
I20260812 06:37:40.696686  2590 catalog_manager.cc:1638] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
I20260812 06:37:40.722210  2425 master_runner.cc:264] No leader master found from master a9242b59964e463a8041ddc8a472ba7d
I20260812 06:37:40.722325  2425 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
W20260812 06:37:40.802961  2570 flags.cc:432] Enabled unsafe flag: --hybrid_clock_inject_init_delay_ms=100
W20260812 06:37:40.803300  2570 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
W20260812 06:37:40.803337  2570 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
W20260812 06:37:40.803403  2570 flags.cc:432] Enabled unsafe flag: --never_fsync=true
W20260812 06:37:40.808732  2570 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
W20260812 06:37:40.808853  2570 flags.cc:432] Enabled experimental flag: --wall_clock_jump_detection=enabled
W20260812 06:37:40.808975  2570 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.201.1
I20260812 06:37:40.814781  2570 tablet_server_runner.cc:78] Tablet server non-default flags:
--log_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/logs
--logbuflevel=-1
--logtostderr=true
--builtin_ntp_poll_interval_ms=100
--builtin_ntp_servers=127.0.201.20:42405
--hybrid_clock_inject_init_delay_ms=100
--ntp_initial_sync_wait_secs=10
--time_source=builtin
--fs_data_dirs=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data
--fs_wal_dir=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
--ipki_server_key_size=768
--openssl_security_level_override=0
--rpc_bind_addresses=127.0.201.1:41877
--rpc_server_allow_ephemeral_ports=true
--wall_clock_jump_detection=enabled
--metrics_log_interval_ms=1000
--server_dump_info_format=pb
--server_dump_info_path=/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/info.pb
--webserver_interface=127.0.201.1
--webserver_port=37863
--tserver_master_addrs=127.0.201.62:41113,127.0.201.61:32975,127.0.201.60:44735
--never_fsync=true
--heap_profile_path=/tmp/kudu.2570
--redact=none
--unlock_experimental_flags=true
--unlock_unsafe_flags=true
--enable_minidumps=false
--local_ip_for_outbound_sockets=127.0.201.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:40.816385  2570 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:37:40.817996  2570 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:37:40.823655  2570 server_base.cc:839] enabling wall clock jump detection
I20260812 06:37:40.823954  2570 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
W20260812 06:37:40.824110  2570 hybrid_clock.cc:606] Injecting 100ms delay in HybridClock initialization process
I20260812 06:37:40.925583  2570 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
I20260812 06:37:40.926808  2570 hybrid_clock.cc:648] HybridClock initialized: now 1786516660926783 us; error 33 us; skew 500 ppm
I20260812 06:37:40.929167  2570 webserver.cc:533] Webserver started at http://127.0.201.1:37863/ using document root <none> and password file <none>
I20260812 06:37:40.929816  2570 fs_manager.cc:362] Metadata directory not provided
I20260812 06:37:40.929886  2570 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:37:40.933786  2570 fs_manager.cc:714] Time spent opening directory manager: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:37:40.935993  2623 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.937139  2570 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:37:40.937258  2570 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data,/tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
uuid: "65129651abb24337ab27521ba563799e"
format_stamp: "Formatted at 2026-08-12 06:37:36 on dist-test-slave-ncp9"
I20260812 06:37:40.937680  2570 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
metadata directory: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/wal
1 data directories: /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:37:40.968037  2570 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:37:40.968824  2570 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:37:40.969058  2570 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:37:40.969802  2570 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:37:40.970949  2570 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:37:40.971011  2570 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.971074  2570 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:37:40.971102  2570 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:37:40.983140  2570 rpc_server.cc:307] RPC server started. Bound to: 127.0.201.1:41877
I20260812 06:37:40.983170  2808 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.201.1:41877 every 8 connection(s)
I20260812 06:37:40.984301  2570 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskLic91R/test-tmp/hybrid_clock-test.0.HybridClockTest.SlowClockInitialisation.1786516653313459-804-0/minicluster-data/ts-0/data/info.pb
I20260812 06:37:40.987270   804 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskLic91R/build/debug/bin/kudu as pid 2570
I20260812 06:37:40.996130  2809 heartbeater.cc:344] Connected to a master server at 127.0.201.60:44735
I20260812 06:37:40.996419  2809 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:40.997148  2809 heartbeater.cc:507] Master 127.0.201.60:44735 requested a full tablet report, sending...
I20260812 06:37:40.997248  2811 heartbeater.cc:344] Connected to a master server at 127.0.201.62:41113
I20260812 06:37:40.997337  2811 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:40.997541  2811 heartbeater.cc:507] Master 127.0.201.62:41113 requested a full tablet report, sending...
I20260812 06:37:40.999022  2497 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:40.999164  2812 heartbeater.cc:344] Connected to a master server at 127.0.201.61:32975
I20260812 06:37:40.999258  2812 heartbeater.cc:461] Registering TS with master...
I20260812 06:37:40.999488  2812 heartbeater.cc:507] Master 127.0.201.61:32975 requested a full tablet report, sending...
I20260812 06:37:41.000414  2219 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:41.000679  2352 ts_manager.cc:194] Registered new tserver with Master: 65129651abb24337ab27521ba563799e (127.0.201.1:41877)
I20260812 06:37:41.016387   804 external_mini_cluster.cc:955] 1 TS(s) registered with all masters
I20260812 06:37:41.446170  2829 raft_consensus.cc:493] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
I20260812 06:37:41.446375  2829 raft_consensus.cc:515] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:41.448346  2829 leader_election.cc:290] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 3 pre-election: Requested pre-vote from peers bf0f8ab4356f4b729715fc4606c0022f (127.0.201.61:32975), a9242b59964e463a8041ddc8a472ba7d (127.0.201.60:44735)
I20260812 06:37:41.453086  2369 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 3 candidate_status { last_received { term: 2 index: 5 } } ignore_live_leader: false dest_uuid: "bf0f8ab4356f4b729715fc4606c0022f" is_pre_election: true
I20260812 06:37:41.453433  2369 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 2 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 2.
I20260812 06:37:41.453902  2194 leader_election.cc:304] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [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: aab405a221a442abaaba95e3dc5eac7c, bf0f8ab4356f4b729715fc4606c0022f; no voters: 
I20260812 06:37:41.454350  2829 raft_consensus.cc:2804] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Leader pre-election won for term 3
I20260812 06:37:41.454432  2829 raft_consensus.cc:493] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
I20260812 06:37:41.454473  2829 raft_consensus.cc:3060] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 2 FOLLOWER]: Advancing to term 3
I20260812 06:37:41.454478  2510 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 3 candidate_status { last_received { term: 2 index: 5 } } ignore_live_leader: false dest_uuid: "a9242b59964e463a8041ddc8a472ba7d" is_pre_election: true
I20260812 06:37:41.454772  2510 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 2 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 2.
I20260812 06:37:41.456281  2829 raft_consensus.cc:515] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 3 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:41.456672  2829 leader_election.cc:290] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [CANDIDATE]: Term 3 election: Requested vote from peers bf0f8ab4356f4b729715fc4606c0022f (127.0.201.61:32975), a9242b59964e463a8041ddc8a472ba7d (127.0.201.60:44735)
I20260812 06:37:41.457063  2510 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 3 candidate_status { last_received { term: 2 index: 5 } } ignore_live_leader: false dest_uuid: "a9242b59964e463a8041ddc8a472ba7d"
I20260812 06:37:41.457075  2369 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "aab405a221a442abaaba95e3dc5eac7c" candidate_term: 3 candidate_status { last_received { term: 2 index: 5 } } ignore_live_leader: false dest_uuid: "bf0f8ab4356f4b729715fc4606c0022f"
I20260812 06:37:41.457211  2369 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 2 FOLLOWER]: Advancing to term 3
I20260812 06:37:41.457217  2510 raft_consensus.cc:3060] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 2 FOLLOWER]: Advancing to term 3
I20260812 06:37:41.459170  2510 raft_consensus.cc:2468] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 3 FOLLOWER]: Leader election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 3.
I20260812 06:37:41.459234  2369 raft_consensus.cc:2468] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 3 FOLLOWER]: Leader election vote request: Granting yes vote for candidate aab405a221a442abaaba95e3dc5eac7c in term 3.
I20260812 06:37:41.459533  2194 leader_election.cc:304] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [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: aab405a221a442abaaba95e3dc5eac7c, bf0f8ab4356f4b729715fc4606c0022f; no voters: 
I20260812 06:37:41.459733  2829 raft_consensus.cc:2804] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 3 FOLLOWER]: Leader election won for term 3
I20260812 06:37:41.459947  2829 raft_consensus.cc:697] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [term 3 LEADER]: Becoming Leader. State: Replica: aab405a221a442abaaba95e3dc5eac7c, State: Running, Role: LEADER
I20260812 06:37:41.460289  2829 consensus_queue.cc:237] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 4, Committed index: 4, Last appended: 2.5, Last appended by leader: 5, Current term: 3, Majority size: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } }
I20260812 06:37:41.461973  2835 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: New leader aab405a221a442abaaba95e3dc5eac7c. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.462116  2835 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:41.462461  2837 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:37:41.463495  2837 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:37:41.463894  2837 catalog_manager.cc:1295] Loaded cluster ID: b572b5f0d6f94030b06af0eea1141d4d
I20260812 06:37:41.463941  2837 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:37:41.464454  2837 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:37:41.464931  2837 catalog_manager.cc:6103] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c: Loaded TSK: 0
I20260812 06:37:41.465533  2837 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:37:41.955478  2510 raft_consensus.cc:1275] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [term 3 FOLLOWER]: Refusing update from remote peer aab405a221a442abaaba95e3dc5eac7c: Log matching property violated. Preceding OpId in replica: term: 1 index: 4. Preceding OpId from leader: term: 3 index: 6. (index mismatch)
I20260812 06:37:41.956187  2835 consensus_queue.cc:1048] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [LEADER]: Connected to new peer: Peer: permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 6, Last known committed idx: 4, Time since last communication: 0.000s
I20260812 06:37:41.960855  2838 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: New leader aab405a221a442abaaba95e3dc5eac7c. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.961030  2838 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:41.961143  2369 raft_consensus.cc:1275] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [term 3 FOLLOWER]: Refusing update from remote peer aab405a221a442abaaba95e3dc5eac7c: Log matching property violated. Preceding OpId in replica: term: 1 index: 4. Preceding OpId from leader: term: 3 index: 6. (index mismatch)
I20260812 06:37:41.961808  2835 consensus_queue.cc:1048] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [LEADER]: Connected to new peer: Peer: permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 6, Last known committed idx: 4, Time since last communication: 0.000s
I20260812 06:37:41.962217  2829 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.962323  2829 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:41.962383  2835 sys_catalog.cc:455] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.962458  2835 sys_catalog.cc:458] T 00000000000000000000000000000000 P aab405a221a442abaaba95e3dc5eac7c [sys.catalog]: This master's current role is: LEADER
I20260812 06:37:41.962852  2849 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.963109  2849 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:41.963124  2838 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.963246  2838 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9242b59964e463a8041ddc8a472ba7d [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:41.964640  2857 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.964767  2857 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:41.964848  2859 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.964954  2859 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:41.965269  2850 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: SysCatalogTable state changed. Reason: New leader aab405a221a442abaaba95e3dc5eac7c. Latest consensus state: current_term: 3 leader_uuid: "aab405a221a442abaaba95e3dc5eac7c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "aab405a221a442abaaba95e3dc5eac7c" member_type: VOTER last_known_addr { host: "127.0.201.62" port: 41113 } } peers { permanent_uuid: "bf0f8ab4356f4b729715fc4606c0022f" member_type: VOTER last_known_addr { host: "127.0.201.61" port: 32975 } } peers { permanent_uuid: "a9242b59964e463a8041ddc8a472ba7d" member_type: VOTER last_known_addr { host: "127.0.201.60" port: 44735 } } }
I20260812 06:37:41.965378  2850 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf0f8ab4356f4b729715fc4606c0022f [sys.catalog]: This master's current role is: FOLLOWER
I20260812 06:37:42.004791  2219 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.201.1:35343
I20260812 06:37:42.057443   804 webui_checker.cc:88] shutting down CURL thread
I20260812 06:37:42.071470   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 2570
I20260812 06:37:42.094488   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 2164
I20260812 06:37:42.112561   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 2275
I20260812 06:37:42.124502   804 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskLic91R/build/debug/bin/kudu with pid 2425
2026-08-12T06:37:42Z chronyd exiting
[       OK ] HybridClockTest.SlowClockInitialisation (6814 ms)
[ RUN      ] HybridClockTest.TimeSourceAutoSelection
I20260812 06:37:42.162436   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:42.162494   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:42.162511   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516662162511 us; error 0 us; skew 500 ppm
W20260812 06:37:42.164619  2871 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:42.164683  2865 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:37:42.164690  2867 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:37:42.165359   804 hybrid_clock.cc:561] auto-selected time source 'builtin' for the hybrid clock
I20260812 06:37:42.165421   804 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
I20260812 06:37:42.166275   804 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=60 seconds for the clock to synchronize
I20260812 06:37:42.168186   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516662168290 us; error 358 us; skew 500 ppm
W20260812 06:37:42.168376   804 builtin_ntp.cc:688] coult not shutdown socket: Network error: shutdown error: Transport endpoint is not connected (error 107)
[       OK ] HybridClockTest.TimeSourceAutoSelection (7 ms)
[ RUN      ] HybridClockTest.AutoTimeSourceNoDedicatedNtpServer
I20260812 06:37:42.170298   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:42.170367   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:42.170388   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516662170389 us; error 0 us; skew 500 ppm
W20260812 06:37:42.170917  2878 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.171139  2882 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.171188  2879 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.171152  2881 instance_detector.cc:116] could not retrieve GCE instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.171496  2884 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.171538  2885 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.171587  2886 instance_detector.cc:116] could not retrieve GCE instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.171630  2889 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: An unknown option was passed in to libcurl
I20260812 06:37:42.171829   804 hybrid_clock.cc:561] auto-selected time source 'builtin' for the hybrid clock
W20260812 06:37:42.172014  2891 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.172098  2892 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.172142  2893 instance_detector.cc:116] could not retrieve GCE instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.172215  2894 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: An unknown option was passed in to libcurl
I20260812 06:37:42.172358   804 hybrid_clock.cc:561] auto-selected time source 'system' for the hybrid clock
W20260812 06:37:42.172533  2896 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.172582  2897 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.172706  2902 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: An unknown option was passed in to libcurl
W20260812 06:37:42.172736  2901 instance_detector.cc:116] could not retrieve GCE instance metadata: Network error: curl error: An unknown option was passed in to libcurl
I20260812 06:37:42.172859   804 hybrid_clock.cc:561] auto-selected time source 'system' for the hybrid clock
[       OK ] HybridClockTest.AutoTimeSourceNoDedicatedNtpServer (4 ms)
[ RUN      ] HybridClockTest.ClockJumpDetection
I20260812 06:37:42.174044   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:42.174088   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:42.174101   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516662174101 us; error 0 us; skew 500 ppm
I20260812 06:37:42.174156   804 hybrid_clock.cc:584] initializing the hybrid clock with 'mock' time source
I20260812 06:37:42.174445   804 hybrid_clock.cc:648] HybridClock initialized: now 0 us; error 0 us; skew 500 ppm
[       OK ] HybridClockTest.ClockJumpDetection (1 ms)
[ RUN      ] HybridClockTest.TestNtpDiagnostics
I20260812 06:37:42.175911   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:42.175953   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:42.175966   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516662175967 us; error 0 us; skew 500 ppm
I20260812 06:37:42.176008   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system' time source
I20260812 06:37:42.176177   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516662176174 us; error 272377 us; skew 500 ppm
[       OK ] HybridClockTest.TestNtpDiagnostics (3073 ms)
[----------] 13 tests from HybridClockTest (11913 ms total)

[----------] 2 tests from Perf/HybridClockJumpProtectionTest
[ RUN      ] Perf/HybridClockJumpProtectionTest.BasicPerf/0
I20260812 06:37:45.249029   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:45.249071   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:45.249085   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516665249085 us; error 0 us; skew 500 ppm
I20260812 06:37:45.984952   804 hybrid_clock-test.cc:697] 1000000 iterations in 735.748074 ms
[       OK ] Perf/HybridClockJumpProtectionTest.BasicPerf/0 (737 ms)
[ RUN      ] Perf/HybridClockJumpProtectionTest.BasicPerf/1
I20260812 06:37:45.986116   804 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:37:45.986158   804 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:37:45.986173   804 hybrid_clock.cc:648] HybridClock initialized: now 1786516665986173 us; error 0 us; skew 500 ppm
I20260812 06:37:46.770188   804 hybrid_clock-test.cc:697] 1000000 iterations in 783.923151 ms
[       OK ] Perf/HybridClockJumpProtectionTest.BasicPerf/1 (785 ms)
[----------] 2 tests from Perf/HybridClockJumpProtectionTest (1522 ms total)

[----------] Global test environment tear-down
[==========] 17 tests from 3 test suites ran. (13449 ms total)
[  PASSED  ] 17 tests.
