[MDEV-15317] galera_new_cluster does not return error with sst mariabackup or xtrabackup-v2 nevertheless MariaDB-backup or percona-xtrabackup are not installed Created: 2018-02-14  Updated: 2021-12-23  Resolved: 2021-12-23

Status: Closed
Project: MariaDB Server
Component/s: Galera SST
Affects Version/s: 10.2.13
Fix Version/s: N/A

Type: Bug Priority: Major
Reporter: Zdravelina Sokolovska (Inactive) Assignee: Jan Lindström (Inactive)
Resolution: Fixed Votes: 0
Labels: None


 Description   

galera_new_cluster does not return error with sst mariabackup or xtrabackup-v2 nevertheless MariaDB-backup or percona-xtrabackup are not installed

that's followed by joiner nodes not be able to establish cluster but
the reason to fail to join it's not clear from logs

with sst
wsrep_sst_method=mariabackup
or
wsrep_sst_method=xtrabackup-v2

1.run galera_new_cluster on Node where mariabackup or xtrabackup are not installed
2. join node where the related backup is installed – fails but reason to fail to join it's not clear from logs

mariabackup --version
-bash: mariabackup: command not found

  1. xtrabackup --version
    -bash: xtrabackup: command not found
  1. galera_new_cluster

2018-02-14 17:16:24 140197224081536 [Note] WSREP: Read nil XID from storage engines, skipping position init
2018-02-14 17:16:24 140197224081536 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
2018-02-14 17:16:24 140197224081536 [Note] WSREP: wsrep_load(): Galera 25.3.22(r3764) by Codership Oy <info@codership.com> loaded successfully.
2018-02-14 17:16:24 140197224081536 [Note] WSREP: CRC-32C: using "slicing-by-8" algorithm.
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Found saved state: 68dc3337-1198-11e8-b694-afb950b338bc:0, safe_to_bootstrap: 1
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = 192.168.104.191; base_port = 456            7; cert.log_conflicts = no; debug = no; evs.auto_evict = 0; evs.delay_margin = PT1S; evs.delayed_keep_period = PT30S; evs.inactive_check_period =             PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period             = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /var/lib/mysql/; gcache.keep_pages_s            ize = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//galera.cache; gcache.page_size = 128M; gcache.recover = no; gcache.size = 128M; gcomm            .thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 1.0; gcs.fc_limit = 16; gcs.fc_master_slave = no; gcs.max_packet_size = 64500; gcs.max_throttl            e = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.segment = 0; gmcast.version = 0;             pc.announce_timeout = PT3S; pc.checksum = false; p
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Assign initial position for certification: 0, protocol version: -1
2018-02-14 17:16:24 140197224081536 [Note] WSREP: wsrep_sst_grab()
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Start replication
2018-02-14 17:16:24 140197224081536 [Note] WSREP: 'wsrep-new-cluster' option used, bootstrapping the cluster
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Setting initial position to 68dc3337-1198-11e8-b694-afb950b338bc:0
2018-02-14 17:16:24 140197224081536 [Note] WSREP: protonet asio version 0
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Using CRC-32C for message checksums.
2018-02-14 17:16:24 140197224081536 [Note] WSREP: backend: asio
2018-02-14 17:16:24 140197224081536 [Note] WSREP: gcomm thread scheduling priority set to other:0
2018-02-14 17:16:24 140197224081536 [Warning] WSREP: access file(/var/lib/mysql//gvwstate.dat) failed(No such file or directory)
2018-02-14 17:16:24 140197224081536 [Note] WSREP: restore pc from disk failed
2018-02-14 17:16:24 140197224081536 [Note] WSREP: GMCast version 0
2018-02-14 17:16:24 140197224081536 [Note] WSREP: (0526b743, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
2018-02-14 17:16:24 140197224081536 [Note] WSREP: (0526b743, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
2018-02-14 17:16:24 140197224081536 [Note] WSREP: EVS version 0
2018-02-14 17:16:24 140197224081536 [Note] WSREP: gcomm: bootstrapping new group 'cluster1'
2018-02-14 17:16:24 140197224081536 [Note] WSREP: start_prim is enabled, turn off pc_recovery
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Node 0526b743 state prim
2018-02-14 17:16:24 140197224081536 [Note] WSREP: view(view_id(PRIM,0526b743,1) memb {
        0526b743,0
} joined {
} left {
} partitioned {
})
2018-02-14 17:16:24 140197224081536 [Note] WSREP: save pc into disk
2018-02-14 17:16:24 140197224081536 [Note] WSREP: discarding pending addr without UUID: tcp://192.168.104.191:4567
2018-02-14 17:16:24 140197224081536 [Note] WSREP: discarding pending addr proto entry 0x55e6b65640c0
2018-02-14 17:16:24 140197224081536 [Note] WSREP: discarding pending addr without UUID: tcp://192.168.104.196:4567
2018-02-14 17:16:24 140197224081536 [Note] WSREP: discarding pending addr proto entry 0x55e6b656c870
2018-02-14 17:16:24 140197224081536 [Note] WSREP: gcomm: connected
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Opened channel 'cluster1'
2018-02-14 17:16:24 140197224081536 [Note] WSREP: Waiting for SST to complete.
2018-02-14 17:16:24 140196884485888 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2018-02-14 17:16:24 140196884485888 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 05278619-119a-11e8-b2d2-1aba46c66d59
2018-02-14 17:16:24 140196884485888 [Note] WSREP: STATE EXCHANGE: sent state msg: 05278619-119a-11e8-b2d2-1aba46c66d59
2018-02-14 17:16:24 140196884485888 [Note] WSREP: STATE EXCHANGE: got state msg: 05278619-119a-11e8-b2d2-1aba46c66d59 from 0 (t4w1)
2018-02-14 17:16:24 140196884485888 [Note] WSREP: Quorum results:
        version    = 4,
        component  = PRIMARY,
        conf_id    = 0,
        members    = 1/1 (joined/total),
        act_id     = 0,
        last_appl. = -1,
        protocols  = 0/7/3 (gcs/repl/appl),
        group UUID = 68dc3337-1198-11e8-b694-afb950b338bc
2018-02-14 17:16:24 140196884485888 [Note] WSREP: Flow-control interval: [16, 16]
2018-02-14 17:16:24 140196884485888 [Note] WSREP: Trying to continue unpaused monitor
2018-02-14 17:16:24 140196884485888 [Note] WSREP: Restored state OPEN -> JOINED (0)
2018-02-14 17:16:24 140196884485888 [Note] WSREP: Member 0.0 (t4w1) synced with group.
2018-02-14 17:16:24 140196884485888 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2018-02-14 17:16:24 140197131990784 [Note] WSREP: New cluster view: global state: 68dc3337-1198-11e8-b694-afb950b338bc:0, view# 1: Primary, numbe            r of nodes: 1, my index: 0, protocol version 3
2018-02-14 17:16:24 140197224081536 [Note] WSREP: SST complete, seqno: 0
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: For Galera, using innodb_lock_schedule_algorithm=fcfs
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Uses event mutexes
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Compressed tables use zlib 1.2.7
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Using Linux native AIO
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Number of pools: 1
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Using generic crc32 instructions
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Completed initialization of buffer pool
2018-02-14 17:16:24 140196001666816 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See t            he man page of setpriority().
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: 128 out of 128 rollback segments are active.
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Creating shared tablespace for temporary tables
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: Waiting for purge to start
2018-02-14 17:16:24 140197224081536 [Note] InnoDB: 5.7.21 started; log sequence number 1620290
2018-02-14 17:16:24 140195626206976 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2018-02-14 17:16:24 140197224081536 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-14 17:16:24 140195626206976 [Note] InnoDB: Buffer pool(s) load completed at 180214 17:16:24
2018-02-14 17:16:24 140197224081536 [Note] Server socket created on IP: '0.0.0.0'.
2018-02-14 17:16:24 140197224081536 [Note] Reading of all Master_info entries succeded
2018-02-14 17:16:24 140197224081536 [Note] Added new Master_info '' to hash table
2018-02-14 17:16:24 140197224081536 [Note] /usr/sbin/mysqld: ready for connections.
Version: '10.2.13-MariaDB'  socket: '/var/lib/mysql/mysql.sock'  port: 3306  MariaDB Server
2018-02-14 17:16:24 140197131990784 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2018-02-14 17:16:24 140197131990784 [Note] WSREP: REPL Protocols: 7 (3, 2)
2018-02-14 17:16:24 140197131990784 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2018-02-14 17:16:24 140196926449408 [Note] WSREP: Service thread queue flushed.
2018-02-14 17:16:24 140197131990784 [Note] WSREP: Synchronized with group, ready for connections
2018-02-14 17:16:24 140197131990784 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.



 Comments   
Comment by Jan Lindström (Inactive) [ 2018-06-15 ]

May I ask why mariabackup is not installed, in my understanding it is provided on packages? Log above would indicate that SST is completed.

Generated at Thu Feb 08 08:20:21 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.