Details
-
Bug
-
Status: Open (View Workflow)
-
Major
-
Resolution: Unresolved
-
10.5.26
-
None
Description
|
https://buildbot.mariadb.org/#/builders/619/builds/6383/steps/10/logs/stdio |
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)...
|
Succeeded on retry
Galera 26.4.19-5db72dadc5594f1dff2b7dea32b66e79e9773176
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
|
Attachments
Issue Links
- is part of
-
MDEV-33073 always green buildbot
-
- Stalled
-