galera.galera_as_slave_nonprim - WSREP: Send action {0x0, 20985210208632, WRITESET} returned -57 (Socket is not connected)

galera.galera_as_slave_nonprim           w4 [ fail ]  Found warnings/errors in server log file!

        Test ended at 2024-08-30 01:56:34

line

2024-08-30  1:56:15 19 [Warning] WSREP: Send action {0x0, 20985210208632, WRITESET} returned -57 (Socket is not connected)

^ Found warnings in /data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/log/mysqld.2.err

ok

 - saving '/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/log/galera.galera_as_slave_nonprim/' to '/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/log/galera.galera_as_slave_nonprim/'

Retrying test galera.galera_as_slave_nonprim, attempt(2/3)...

CURRENT_TEST: galera.galera_as_slave_nonprim

$ /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd --defaults-group-suffix=.2 --defaults-file=/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/my.cnf --log-output=file --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --core-file --loose-debug-sync-timeout=300

2024-08-30  1:55:52 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)

2024-08-30  1:55:52 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)

2024-08-30  1:55:52 0 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:19069' which makes it impossible for another host to reach this one. Please set it to the address which this node can be connected at by other cluster members.

2024-08-30  1:55:52 0 [Note] Starting MariaDB 10.5.27-MariaDB-log source revision d2e8c3c76ec7556e9e79f13d7e3bb135f0dddaf8 server_uid ECWS3r2M/iqvpx3vD2fXmJLvPd4= as process 74242

2024-08-30  1:55:52 0 [Note] WSREP: Loading provider /usr/local/lib/libgalera_smm.so initial position: 00000000-0000-0000-0000-000000000000:-1

2024-08-30  1:55:52 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/local/lib/libgalera_smm.so'

2024-08-30  1:55:52 0 [Note] WSREP: wsrep_load(): Galera 26.4.19(r0dee28ef8) by Codership Oy <info@codership.com> loaded successfully.

2024-08-30  1:55:52 0 [Note] WSREP: Resolved symbol 'wsrep_node_isolation_mode_set_v1'

2024-08-30  1:55:52 0 [Note] WSREP: CRC-32C: using 64-bit x86 acceleration.

2024-08-30  1:55:52 0 [Warning] WSREP: Could not open state file for reading: '/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/mysqld.2/data//grastate.dat'

2024-08-30  1:55:52 0 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1

2024-08-30  1:55:52 0 [Note] WSREP: Preallocating 10487064/10487064 bytes in '/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/mysqld.2/data//galera.cache'...

2024-08-30  1:55:52 0 [Note] WSREP: GCache DEBUG: opened preamble:

Version: 0

UUID: 00000000-0000-0000-0000-000000000000

Seqno: -1 - -1

Offset: -1

Synced: 0

2024-08-30  1:55:52 0 [Note] WSREP: Skipped GCache ring buffer recovery: could not determine history UUID.

2024-08-30  1:55:52 0 [Note] WSREP: Passing config to GCS: base_dir = /data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/mysqld.2/data/; base_host = 127.0.0.1; base_port = 19067; cert.log_conflicts = no; cert.optimistic_pa = yes; debug = no; evs.auto_evict = 0; evs.delay_margin = PT1S; evs.delayed_keep_period = PT30S; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT30S; evs.install_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT10S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.keep_plaintext_size = 128M; gcache.mem_size = 0; gcache.name = galera.cache; gcache.page_size = 128M; gcache.recover = yes; gcache.size = 10M; gcomm.thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 1.0; gcs.fc_limit = 16; gcs.fc_master_slave = no; gcs.fc_single_primary = no; gcs.max_

2024-08-30  1:55:52 0 [Note] WSREP: Start replication

2024-08-30  1:55:52 0 [Note] WSREP: Connecting with bootstrap option: 0

2024-08-30  1:55:52 0 [Note] WSREP: Setting GCS initial position to 00000000-0000-0000-0000-000000000000:-1

2024-08-30  1:55:52 0 [Note] WSREP: protonet asio version 0

2024-08-30  1:55:52 0 [Note] WSREP: Using CRC-32C for message checksums.

2024-08-30  1:55:52 0 [Note] WSREP: backend: asio

2024-08-30  1:55:52 0 [Note] WSREP: gcomm thread scheduling priority set to other:0 

2024-08-30  1:55:52 0 [Note] WSREP: access file(/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/mysqld.2/data//gvwstate.dat) failed(No such file or directory)

2024-08-30  1:55:52 0 [Note] WSREP: restore pc from disk failed

2024-08-30  1:55:52 0 [Note] WSREP: GMCast version 0

2024-08-30  1:55:52 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') listening at tcp://0.0.0.0:19067

2024-08-30  1:55:52 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') multicast: , ttl: 1

2024-08-30  1:55:52 0 [Note] WSREP: EVS version 1

2024-08-30  1:55:52 0 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:19064'

2024-08-30  1:55:52 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') connection established to 38cac092-b496 tcp://127.0.0.1:19064

2024-08-30  1:55:52 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') connection established to 392a3844-b1ba tcp://127.0.0.1:19070

2024-08-30  1:55:52 0 [Note] WSREP: EVS version upgrade 0 -> 1

2024-08-30  1:55:52 0 [Note] WSREP: declaring 38cac092-b496 at tcp://127.0.0.1:19064 stable

2024-08-30  1:55:52 0 [Note] WSREP: PC protocol upgrade 0 -> 1

2024-08-30  1:55:52 0 [Note] WSREP: Node 38cac092-b496 state prim

2024-08-30  1:55:52 0 [Note] WSREP: view(view_id(PRIM,38cac092-b496,2) memb {

	38cac092-b496,0

	3927276d-9b67,0

} joined {

} left {

} partitioned {

})

2024-08-30  1:55:52 0 [Note] WSREP: save pc into disk

2024-08-30  1:55:52 0 [Note] WSREP: forgetting 392a3844-b1ba (tcp://127.0.0.1:19070)

2024-08-30  1:55:53 0 [Note] WSREP: gcomm: connected

2024-08-30  1:55:53 0 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636

2024-08-30  1:55:53 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)

2024-08-30  1:55:53 0 [Note] WSREP: Opened channel 'my_wsrep_cluster'

2024-08-30  1:55:53 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2

2024-08-30  1:55:53 0 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.

2024-08-30  1:55:53 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 397869ee-6662-11ef-88be-da486847e6e8

2024-08-30  1:55:53 0 [Note] WSREP: STATE EXCHANGE: got state msg: 397869ee-6662-11ef-88be-da486847e6e8 from 0 (hz-freebsd-bbw1)

2024-08-30  1:55:53 1 [Note] WSREP: Starting rollbacker thread 1

2024-08-30  1:55:53 0 [Note] WSREP: STATE EXCHANGE: got state msg: 397869ee-6662-11ef-88be-da486847e6e8 from 1 (hz-freebsd-bbw1)

2024-08-30  1:55:53 0 [Note] WSREP: Quorum results:

	version    = 6,

	component  = PRIMARY,

	conf_id    = 1,

	members    = 1/2 (joined/total),

	act_id     = 1,

	last_appl. = 0,

	protocols  = 4/11/4 (gcs/repl/appl),

	vote policy= 0,

	group UUID = 38d218db-6662-11ef-9d66-06f2e4920a64

2024-08-30  1:55:53 2 [Note] WSREP: Starting applier thread 2

2024-08-30  1:55:53 0 [Note] WSREP: Flow-control interval: [23, 23]

2024-08-30  1:55:53 0 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 2)

2024-08-30  1:55:53 2 [Note] WSREP: ####### processing CC 2, local, ordered

2024-08-30  1:55:53 2 [Note] WSREP: Process first view: 38d218db-6662-11ef-9d66-06f2e4920a64 my uuid: 3927276d-6662-11ef-9b67-fafcc2d2d4b2

2024-08-30  1:55:53 2 [Note] WSREP: Server hz-freebsd-bbw1 connected to cluster at position 38d218db-6662-11ef-9d66-06f2e4920a64:2 with ID 3927276d-6662-11ef-9b67-fafcc2d2d4b2

2024-08-30  1:55:53 2 [Note] WSREP: Server status change disconnected -> connected

2024-08-30  1:55:53 2 [Note] WSREP: ####### My UUID: 3927276d-6662-11ef-9b67-fafcc2d2d4b2

2024-08-30  1:55:53 2 [Note] WSREP: Cert index reset to 00000000-0000-0000-0000-000000000000:-1 (proto: 11), state transfer needed: yes

2024-08-30  1:55:53 0 [Note] WSREP: Service thread queue flushed.

2024-08-30  1:55:53 2 [Note] WSREP: ####### Assign initial position for certification: 00000000-0000-0000-0000-000000000000:-1, protocol version: -1

2024-08-30  1:55:53 2 [Note] WSREP: State transfer required: 

	Group state: 38d218db-6662-11ef-9d66-06f2e4920a64:2

	Local state: 00000000-0000-0000-0000-000000000000:-1

2024-08-30  1:55:53 2 [Note] WSREP: Server status change connected -> joiner

2024-08-30  1:55:53 0 [Note] WSREP: Joiner monitor thread started to monitor

2024-08-30  1:55:53 0 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1:19069' --datadir '/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/mysqld.2/data/' --defaults-file '/tmp/var_auto_Di_e/4/my.cnf' --defaults-group-suffix '.2' --parent 74242 --progress 0 --binlog 'mysqld-bin' --binlog-index 'mysqld-bin.index' --mysqld-args --defaults-group-suffix=.2 --defaults-file=/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/4/my.cnf --log-output=file --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns

WSREP_SST: [INFO] rsync SST started on joiner (20240830 01:55:53.N)

2024-08-30  1:55:53 2 [Note] WSREP: ####### IST uuid:00000000-0000-0000-0000-000000000000 f: 0, l: 2, STRv: 3

2024-08-30  1:55:53 2 [Note] WSREP: IST receiver addr using tcp://127.0.0.1:19068

2024-08-30  1:55:53 2 [Note] WSREP: Prepared IST receiver for 0-2, listening at: tcp://127.0.0.1:19068

2024-08-30  1:55:53 0 [Note] WSREP: Member 1.0 (hz-freebsd-bbw1) requested state transfer from '*any*'. Selected 0.0 (hz-freebsd-bbw1)(SYNCED) as donor.

2024-08-30  1:55:53 0 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 2)

2024-08-30  1:55:53 2 [Note] WSREP: Requesting state transfer: success, donor: 0

2024-08-30  1:55:53 2 [Note] WSREP: Resetting GCache seqno map due to different histories.

2024-08-30  1:55:53 2 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 38d218db-6662-11ef-9d66-06f2e4920a64:2

2024-08-30  1:55:53 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') connection established to 392a3844-b1ba tcp://127.0.0.1:19070

2024-08-30  1:55:53 0 [Warning] WSREP: discarding established (time wait) 392a3844-b1ba (tcp://127.0.0.1:19070) 

2024-08-30  1:55:54 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') connection established to 392a3844-b1ba tcp://127.0.0.1:19070

2024-08-30  1:55:54 0 [Warning] WSREP: discarding established (time wait) 392a3844-b1ba (tcp://127.0.0.1:19070) 

2024-08-30  1:55:55 0 [Note] WSREP:  cleaning up 392a3844-b1ba (tcp://127.0.0.1:19070)

2024-08-30  1:55:55 0 [Note] WSREP: 0.0 (hz-freebsd-bbw1): State transfer to 1.0 (hz-freebsd-bbw1) complete.

2024-08-30  1:55:55 0 [Note] WSREP: Member 0.0 (hz-freebsd-bbw1) synced with group.

2024-08-30  1:55:55 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') turning message relay requesting off

2024-08-30  1:55:55 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') connection established to 392a3844-b1ba tcp://127.0.0.1:19070

WSREP_SST: [INFO] Extracting binlog files: (20240830 01:55:55.N)

x mysqld-bin.000002

WSREP_SST: [INFO] Galera co-ords from recovery: 38d218db-6662-11ef-9d66-06f2e4920a64:2 0 (20240830 01:55:56.N)

WSREP_SST: [INFO] rsync SST completed on joiner (20240830 01:55:56.N)

WSREP_SST: [INFO] Joiner cleanup: rsync PID=75166, stunnel PID=0 (20240830 01:55:56.N)

WSREP_SST: [INFO] Joiner cleanup done. (20240830 01:55:56.N)

2024-08-30  1:55:56 3 [Note] WSREP: SST received

2024-08-30  1:55:56 3 [Note] WSREP: Server status change joiner -> initializing

2024-08-30  1:55:56 0 [Note] Plugin 'partition' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'SEQUENCE' is disabled.

2024-08-30  1:55:56 0 [Note] InnoDB: Uses event mutexes

2024-08-30  1:55:56 0 [Note] InnoDB: Compressed tables use zlib 1.3

2024-08-30  1:55:56 0 [Note] InnoDB: Number of pools: 1

2024-08-30  1:55:56 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions

2024-08-30  1:55:56 0 [Note] InnoDB: Initializing buffer pool, total size = 8388608, chunk size = 8388608

2024-08-30  1:55:56 0 [Note] InnoDB: Completed initialization of buffer pool

2024-08-30  1:55:56 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=55980,55980

2024-08-30  1:55:56 0 [Note] InnoDB: 128 rollback segments are active.

2024-08-30  1:55:56 0 [Note] InnoDB: Creating shared tablespace for temporary tables

2024-08-30  1:55:56 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...

2024-08-30  1:55:56 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.

2024-08-30  1:55:56 0 [Note] InnoDB: 10.5.27 started; log sequence number 55992; transaction id 44

2024-08-30  1:55:56 0 [Note] InnoDB: Loading buffer pool(s) from /tmp/var_auto_Di_e/4/mysqld.2/data/ib_buffer_pool

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.

2024-08-30  1:55:56 0 [Note] InnoDB: Buffer pool(s) load completed at 240830  1:55:56

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_CMP' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'FEEDBACK' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_MUTEXES' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'user_variables' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.

2024-08-30  1:55:56 0 [Note] Plugin 'unix_socket' is disabled.

2024-08-30  1:55:56 0 [Warning] /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd: unknown variable 'loose-feedback-debug-startup-interval=20'

2024-08-30  1:55:56 0 [Warning] /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd: unknown variable 'loose-feedback-debug-first-interval=60'

2024-08-30  1:55:56 0 [Warning] /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd: unknown variable 'loose-feedback-debug-interval=60'

2024-08-30  1:55:56 0 [Warning] /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd: unknown option '--loose-pam-debug'

2024-08-30  1:55:56 0 [Warning] /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd: unknown option '--loose-aria'

2024-08-30  1:55:56 0 [Warning] /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd: unknown variable 'loose-debug-sync-timeout=300'

2024-08-30  1:55:56 0 [Note] Recovering after a crash using mysqld-bin

2024-08-30  1:55:56 0 [Note] Starting crash recovery...

2024-08-30  1:55:56 0 [Note] Crash recovery finished.

2024-08-30  1:55:56 0 [Note] Server socket created on IP: '127.0.0.1'.

2024-08-30  1:55:56 0 [Note] WSREP: wsrep_init_schema_and_SR 0x0

2024-08-30  1:55:56 0 [Note] WSREP: Server initialized

2024-08-30  1:55:56 0 [Note] WSREP: Server status change initializing -> initialized

2024-08-30  1:55:56 3 [Note] WSREP: Recovered position from storage: 38d218db-6662-11ef-9d66-06f2e4920a64:2

2024-08-30  1:55:56 3 [Note] WSREP: Server status change initialized -> joined

2024-08-30  1:55:56 3 [Note] WSREP: Recovered view from SST:

  id: 38d218db-6662-11ef-9d66-06f2e4920a64:2

  status: primary

  protocol_version: 4

  capabilities: MULTI-MASTER, CERTIFICATION, PARALLEL_APPLYING, REPLAY, ISOLATION, PAUSE, CAUSAL_READ, INCREMENTAL_WS, UNORDERED, PREORDERED, STREAMING, NBO

  final: no

  own_index: 1

  members(2):

	0: 38cac092-6662-11ef-b496-ff08eb544185, hz-freebsd-bbw1

	1: 3927276d-6662-11ef-9b67-fafcc2d2d4b2, hz-freebsd-bbw1

2024-08-30  1:55:56 3 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2024-08-30  1:55:56 6 [Note] WSREP: Recovered cluster id 38d218db-6662-11ef-9d66-06f2e4920a64

2024-08-30  1:55:56 3 [Note] WSREP: SST received: 38d218db-6662-11ef-9d66-06f2e4920a64:2

2024-08-30  1:55:56 3 [Note] WSREP: SST succeeded for position 38d218db-6662-11ef-9d66-06f2e4920a64:2

2024-08-30  1:55:56 2 [Note] WSREP: Installed new state from SST: 38d218db-6662-11ef-9d66-06f2e4920a64:2

2024-08-30  1:55:56 0 [Note] WSREP: Joiner monitor thread ended with total time 3 sec

2024-08-30  1:55:56 0 [Note] Reading of all Master_info entries succeeded

2024-08-30  1:55:56 0 [Note] Added new Master_info '' to hash table

2024-08-30  1:55:56 0 [Note] /data/buildbot/workers/prod/amd64-freebsd-14/build/sql/mariadbd: ready for connections.

Version: '10.5.27-MariaDB-log'  socket: '/data/buildbot/workers/prod/amd64-freebsd-14/build/mysql-test/var/tmp/4/mysqld.2.sock'  port: 19061  Source distribution

2024-08-30  1:55:56 2 [Note] WSREP: Cert. index preload up to 2

2024-08-30  1:55:56 0 [Note] WSREP: ####### IST applying starts with 3

2024-08-30  1:55:56 0 [Note] WSREP: ####### IST current seqno initialized to 2

2024-08-30  1:55:56 0 [Note] WSREP: Receiving IST... 0.0% (0/1 events) complete.

2024-08-30  1:55:56 0 [Note] WSREP: IST preload starting at 2

2024-08-30  1:55:56 0 [Note] WSREP: REPL Protocols: 11 (6)

2024-08-30  1:55:56 0 [Note] WSREP: Service thread queue flushed.

2024-08-30  1:55:56 0 [Note] WSREP: ####### Assign initial position for certification: 38d218db-6662-11ef-9d66-06f2e4920a64:1, protocol version: 6

2024-08-30  1:55:56 0 [Note] WSREP: REPL Protocols: 11 (6)

2024-08-30  1:55:56 0 [Note] WSREP: ####### Adjusting cert position: 1 -> 2

2024-08-30  1:55:56 0 [Note] WSREP: Service thread queue flushed.

2024-08-30  1:55:56 0 [Note] WSREP: Lowest cert index boundary for CC from preload: 2

2024-08-30  1:55:56 0 [Note] WSREP: Min available from gcache for CC from preload: 2

2024-08-30  1:55:56 0 [Note] WSREP: Receiving IST... 100.0% (1/1 events) complete.

2024-08-30  1:55:56 2 [Note] WSREP: IST received: 38d218db-6662-11ef-9d66-06f2e4920a64:2

2024-08-30  1:55:57 0 [Note] WSREP: declaring 38cac092-b496 at tcp://127.0.0.1:19064 stable

2024-08-30  1:55:57 0 [Note] WSREP: declaring 392a3844-b1ba at tcp://127.0.0.1:19070 stable

2024-08-30  1:55:57 0 [Note] WSREP: Node 38cac092-b496 state prim

2024-08-30  1:55:57 0 [Note] WSREP: view(view_id(PRIM,38cac092-b496,3) memb {

	38cac092-b496,0

	3927276d-9b67,0

	392a3844-b1ba,0

} joined {

} left {

} partitioned {

})

2024-08-30  1:55:57 0 [Note] WSREP: save pc into disk

2024-08-30  1:55:57 2 [Note] WSREP: Lowest cert index boundary for CC from sst: 2

2024-08-30  1:55:57 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 3

2024-08-30  1:55:57 2 [Note] WSREP: Min available from gcache for CC from sst: 2

2024-08-30  1:55:57 0 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.

2024-08-30  1:55:57 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 3c2e5b1e-6662-11ef-8d45-37c224d4fc15

2024-08-30  1:55:57 0 [Note] WSREP: STATE EXCHANGE: got state msg: 3c2e5b1e-6662-11ef-8d45-37c224d4fc15 from 0 (hz-freebsd-bbw1)

2024-08-30  1:55:57 0 [Note] WSREP: STATE EXCHANGE: got state msg: 3c2e5b1e-6662-11ef-8d45-37c224d4fc15 from 2 (hz-freebsd-bbw1)

2024-08-30  1:55:57 0 [Warning] WSREP: JOIN message from member 1 in non-primary configuration. Ignored.

2024-08-30  1:55:57 0 [Note] WSREP: STATE EXCHANGE: got state msg: 3c2e5b1e-6662-11ef-8d45-37c224d4fc15 from 1 (hz-freebsd-bbw1)

2024-08-30  1:55:57 0 [Note] WSREP: Quorum results:

	version    = 6,

	component  = PRIMARY,

	conf_id    = 2,

	members    = 1/3 (joined/total),

	act_id     = 2,

	last_appl. = 0,

	protocols  = 4/11/4 (gcs/repl/appl),

	vote policy= 0,

	group UUID = 38d218db-6662-11ef-9d66-06f2e4920a64

2024-08-30  1:55:57 0 [Note] WSREP: Flow-control interval: [28, 28]

2024-08-30  1:55:57 2 [Note] WSREP: ####### processing CC 3, local, ordered

2024-08-30  1:55:57 2 [Note] WSREP: ####### My UUID: 3927276d-6662-11ef-9b67-fafcc2d2d4b2

2024-08-30  1:55:57 2 [Note] WSREP: Skipping cert index reset

2024-08-30  1:55:57 2 [Note] WSREP: REPL Protocols: 11 (6)

2024-08-30  1:55:57 2 [Note] WSREP: ####### Adjusting cert position: 2 -> 3

2024-08-30  1:55:57 0 [Note] WSREP: 1.0 (hz-freebsd-bbw1): State transfer from 0.0 (hz-freebsd-bbw1) complete.

2024-08-30  1:55:57 0 [Note] WSREP: Shifting JOINER -> JOINED (TO: 3)

2024-08-30  1:55:57 0 [Note] WSREP: Processing event queue:... nan% (0/0 events) complete.

2024-08-30  1:55:57 0 [Note] WSREP: Member 1.0 (hz-freebsd-bbw1) synced with group.

2024-08-30  1:55:57 0 [Note] WSREP: Processing event queue:... 100.0% (1/1 events) complete.

2024-08-30  1:55:57 0 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 3)

2024-08-30  1:55:57 0 [Note] WSREP: Service thread queue flushed.

2024-08-30  1:55:57 2 [Note] WSREP: ================================================

View:

  id: 38d218db-6662-11ef-9d66-06f2e4920a64:3

  status: primary

  protocol_version: 4

  capabilities: MULTI-MASTER, CERTIFICATION, PARALLEL_APPLYING, REPLAY, ISOLATION, PAUSE, CAUSAL_READ, INCREMENTAL_WS, UNORDERED, PREORDERED, STREAMING, NBO

  final: no

  own_index: 1

  members(3):

	0: 38cac092-6662-11ef-b496-ff08eb544185, hz-freebsd-bbw1

	1: 3927276d-6662-11ef-9b67-fafcc2d2d4b2, hz-freebsd-bbw1

	2: 392a3844-6662-11ef-b1ba-dbfefdd8eef0, hz-freebsd-bbw1

=================================================

2024-08-30  1:55:57 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2024-08-30  1:55:57 2 [Note] WSREP: Lowest cert index boundary for CC from group: 3

2024-08-30  1:55:57 2 [Note] WSREP: Min available from gcache for CC from group: 2

2024-08-30  1:55:57 2 [Note] WSREP: Server hz-freebsd-bbw1 synced with group

2024-08-30  1:55:57 2 [Note] WSREP: Server status change joined -> synced

2024-08-30  1:55:57 2 [Note] WSREP: Synchronized with group, ready for connections

2024-08-30  1:55:57 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2024-08-30  1:55:57 0 [Warning] WSREP: Protocol violation. JOIN message sender 1.0 (hz-freebsd-bbw1) is not in state transfer (SYNCED). Message ignored.

2024-08-30  1:55:57 0 [Note] WSREP: Member 2.0 (hz-freebsd-bbw1) requested state transfer from '*any*'. Selected 0.0 (hz-freebsd-bbw1)(SYNCED) as donor.

2024-08-30  1:55:58 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') turning message relay requesting off

2024-08-30  1:55:59 0 [Note] WSREP: 0.0 (hz-freebsd-bbw1): State transfer to 2.0 (hz-freebsd-bbw1) complete.

2024-08-30  1:55:59 0 [Note] WSREP: Member 0.0 (hz-freebsd-bbw1) synced with group.

2024-08-30  1:56:00 0 [Note] WSREP: 2.0 (hz-freebsd-bbw1): State transfer from 0.0 (hz-freebsd-bbw1) complete.

2024-08-30  1:56:00 0 [Note] WSREP: Member 2.0 (hz-freebsd-bbw1) synced with group.

2024-08-30  1:56:03 17 [Note] Master connection name: ''  Master_info_file: 'master.info'  Relay_info_file: 'relay-log.info'

2024-08-30  1:56:03 17 [Note] 'CHANGE MASTER TO executed'. Previous state master_host='', master_port='3306', master_log_file='', master_log_pos='4'. New state master_host='127.0.0.1', master_port='19063', master_log_file='', master_log_pos='4'.

2024-08-30  1:56:03 18 [Note] Slave I/O thread: Start asynchronous replication to master 'root@127.0.0.1:19063' in log '' at position 4

2024-08-30  1:56:03 19 [Note] Slave SQL thread initialized, starting replication in log 'FIRST' at position 0, relay log './mysqld-relay-bin.000001' position: 4

2024-08-30  1:56:03 19 [Note] WSREP: ready state reached

2024-08-30  1:56:03 18 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:19063',replication started in log 'FIRST' at position 4

2024-08-30  1:56:03 17 [Note] WSREP: turning isolation on

2024-08-30  1:56:03 0 [Note] WSREP: (3927276d-9b67, 'tcp://0.0.0.0:19067') turning message relay requesting on, nonlive peers: tcp://127.0.0.1:19064 tcp://127.0.0.1:19070 

2024-08-30  1:56:14 0 [Note] WSREP: evs::proto(3927276d-9b67, OPERATIONAL, view_id(REG,38cac092-b496,3)) suspecting node: 38cac092-b496

2024-08-30  1:56:14 0 [Note] WSREP: evs::proto(3927276d-9b67, OPERATIONAL, view_id(REG,38cac092-b496,3)) suspected node without join message, declaring inactive

2024-08-30  1:56:14 0 [Note] WSREP: evs::proto(3927276d-9b67, OPERATIONAL, view_id(REG,38cac092-b496,3)) suspecting node: 392a3844-b1ba

2024-08-30  1:56:14 0 [Note] WSREP: evs::proto(3927276d-9b67, OPERATIONAL, view_id(REG,38cac092-b496,3)) suspected node without join message, declaring inactive

2024-08-30  1:56:15 0 [Note] WSREP: view(view_id(NON_PRIM,38cac092-b496,3) memb {

	3927276d-9b67,0

} joined {

} left {

} partitioned {

	38cac092-b496,0

	392a3844-b1ba,0

})

2024-08-30  1:56:15 0 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1

2024-08-30  1:56:15 0 [Note] WSREP: view(view_id(NON_PRIM,3927276d-9b67,4) memb {

	3927276d-9b67,0

} joined {

} left {

} partitioned {

	38cac092-b496,0

	392a3844-b1ba,0

})

2024-08-30  1:56:15 0 [Note] WSREP: Flow-control interval: [16, 16]

2024-08-30  1:56:15 0 [Note] WSREP: Received NON-PRIMARY.

2024-08-30  1:56:15 0 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 4)

2024-08-30  1:56:15 0 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1

2024-08-30  1:56:15 0 [Note] WSREP: Flow-control interval: [16, 16]

2024-08-30  1:56:15 0 [Note] WSREP: Received NON-PRIMARY.

2024-08-30  1:56:15 2 [Note] WSREP: ================================================

View:

  id: 38d218db-6662-11ef-9d66-06f2e4920a64:4

  status: non-primary

  protocol_version: 4

  capabilities: MULTI-MASTER, CERTIFICATION, PARALLEL_APPLYING, REPLAY, ISOLATION, PAUSE, CAUSAL_READ, INCREMENTAL_WS, UNORDERED, PREORDERED, STREAMING, NBO

  final: no

  own_index: 0

  members(1):

	0: 3927276d-6662-11ef-9b67-fafcc2d2d4b2, hz-freebsd-bbw1

=================================================

2024-08-30  1:56:15 2 [Note] WSREP: Non-primary view

2024-08-30  1:56:15 2 [Note] WSREP: Server status change synced -> connected

2024-08-30  1:56:15 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2024-08-30  1:56:15 2 [Note] WSREP: ================================================

View:

  id: 38d218db-6662-11ef-9d66-06f2e4920a64:4

  status: non-primary

  protocol_version: 4

  capabilities: MULTI-MASTER, CERTIFICATION, PARALLEL_APPLYING, REPLAY, ISOLATION, PAUSE, CAUSAL_READ, INCREMENTAL_WS, UNORDERED, PREORDERED, STREAMING, NBO

  final: no

  own_index: 0

  members(1):

	0: 3927276d-6662-11ef-9b67-fafcc2d2d4b2, hz-freebsd-bbw1

=================================================

2024-08-30  1:56:15 2 [Note] WSREP: Non-primary view

2024-08-30  1:56:15 2 [Note] WSREP: Server status change connected -> connected

2024-08-30  1:56:15 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2024-08-30  1:56:15 19 [Warning] WSREP: Send action {0x0, 20985210208632, WRITESET} returned -57 (Socket is not connected)

2024-08-30  1:56:15 19 [ERROR] Slave SQL: Node has dropped from cluster, Gtid 0-4-2, Internal MariaDB error code: 1047

2024-08-30  1:56:15 19 [Note] Slave SQL thread exiting, replication stopped in log 'mysqld-bin.000001' at position 492