fail to force galera backup with sst mariabackup

fail to force galera backup with sst mariabackup

using garbd with --sst option and mariabackup is expected to perform galera backup
of galera targeted donor node

# ls /usr/bin/ | grep wsrep

wsrep_sst_common

wsrep_sst_mariabackup

wsrep_sst_mysqldump

wsrep_sst_rsync

wsrep_sst_xtrabackup

wsrep_sst_xtrabackup-v2

from non galera node force garbd to perform backup with sst mariabackup
garbd --address gcomm://192.168.104.191:4567,192.168.104.195:4567?gmcast.listen_addr=tcp://0.0.0.0:4444 --group cluster1 --donor 192.168.104.195 --sst mariabackup
2018-03-16 19:11:14.535 INFO: CRC-32C: using "slicing-by-8" algorithm.
2018-03-16 19:11:14.535 INFO: Read config:
daemon: 0
name: garb
address: gcomm://192.168.104.191:4567,192.168.104.195:4567?gmcast.listen_addr=tcp://0.0.0.0:4444
group: cluster1
sst: mariabackup
donor: 192.168.104.195
options: gcs.fc_limit=9999999; gcs.fc_factor=1.0; gcs.fc_master_slave=yes
cfg:
log:

2018-03-16 19:11:14.537 INFO: protonet asio version 0
2018-03-16 19:11:14.538 INFO: Using CRC-32C for message checksums.
2018-03-16 19:11:14.538 INFO: backend: asio
2018-03-16 19:11:14.538 INFO: gcomm thread scheduling priority set to other:0
2018-03-16 19:11:14.538 WARN: access file(./gvwstate.dat) failed(No such file or directory)
2018-03-16 19:11:14.538 INFO: restore pc from disk failed
2018-03-16 19:11:14.539 INFO: GMCast version 0
2018-03-16 19:11:14.539 INFO: (08651e2d, 'tcp://0.0.0.0:4444') listening at tcp://0.0.0.0:4444
2018-03-16 19:11:14.539 INFO: (08651e2d, 'tcp://0.0.0.0:4444') multicast: , ttl: 1
2018-03-16 19:11:14.540 INFO: EVS version 0
2018-03-16 19:11:14.540 INFO: gcomm: connecting to group 'cluster1', peer '192.168.104.191:4567,192.168.104.195:4567'
2018-03-16 19:11:14.542 INFO: (08651e2d, 'tcp://0.0.0.0:4444') connection established to 4303cb18 tcp://192.168.104.195:4567
2018-03-16 19:11:14.542 INFO: (08651e2d, 'tcp://0.0.0.0:4444') turning message relay requesting on, nonlive peers:
2018-03-16 19:11:14.542 INFO: (08651e2d, 'tcp://0.0.0.0:4444') connection established to 87cfacec tcp://192.168.104.191:4567
2018-03-16 19:11:15.042 INFO: declaring 4303cb18 at tcp://192.168.104.195:4567 stable
2018-03-16 19:11:15.042 INFO: declaring 87cfacec at tcp://192.168.104.191:4567 stable
2018-03-16 19:11:15.043 INFO: Node 4303cb18 state prim
2018-03-16 19:11:15.044 INFO: view(view_id(PRIM,08651e2d,177) memb

joined {
} left {
} partitioned {
})
2018-03-16 19:11:15.044 INFO: save pc into disk
2018-03-16 19:11:15.541 INFO: gcomm: connected
2018-03-16 19:11:15.541 INFO: Changing maximum packet size to 64500, resulting msg size: 32636
2018-03-16 19:11:15.541 INFO: Shifting CLOSED -> OPEN (TO: 0)
2018-03-16 19:11:15.541 INFO: Opened channel 'cluster1'
2018-03-16 19:11:15.541 INFO: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 3
2018-03-16 19:11:15.542 INFO: STATE_EXCHANGE: sent state UUID: 08fe2e26-293d-11e8-9ae0-fe6979f1469d
2018-03-16 19:11:15.542 INFO: STATE EXCHANGE: sent state msg: 08fe2e26-293d-11e8-9ae0-fe6979f1469d
2018-03-16 19:11:15.543 INFO: STATE EXCHANGE: got state msg: 08fe2e26-293d-11e8-9ae0-fe6979f1469d from 0 (garb)
2018-03-16 19:11:15.543 INFO: STATE EXCHANGE: got state msg: 08fe2e26-293d-11e8-9ae0-fe6979f1469d from 1 (t4w5)
2018-03-16 19:11:15.543 INFO: STATE EXCHANGE: got state msg: 08fe2e26-293d-11e8-9ae0-fe6979f1469d from 2 (t4w1)
2018-03-16 19:11:15.543 INFO: Quorum results:
version = 4,
component = PRIMARY,
conf_id = 175,
members = 2/3 (joined/total),
act_id = 131462,
last_appl. = -1,
protocols = 0/7/3 (gcs/repl/appl),
group UUID = dff6e041-1005-11e8-85c9-965f304f37bc
2018-03-16 19:11:15.543 INFO: Flow-control interval: [9999999, 9999999]
2018-03-16 19:11:15.543 INFO: Trying to continue unpaused monitor
2018-03-16 19:11:15.543 INFO: Shifting OPEN -> PRIMARY (TO: 131462)
2018-03-16 19:11:15.543 INFO: Sending state transfer request: 'mariabackup', size: 11
2018-03-16 19:11:15.544 WARN: Member 0.0 (garb) requested state transfer from '192.168.104.195', but it is impossible to select State Transfer d onor: No route to host
2018-03-16 19:11:15.544 FATAL: State transfer request failed: -113 (No route to host)
2018-03-16 19:11:15.544 WARN: Destroying non-closed object, bad idea
2018-03-16 19:11:15.544 INFO: Closing send monitor...
2018-03-16 19:11:15.544 INFO: Closed send monitor.
2018-03-16 19:11:15.544 INFO: gcomm: terminating thread
2018-03-16 19:11:15.544 INFO: gcomm: joining thread
2018-03-16 19:11:15.544 INFO: gcomm: closing backend
2018-03-16 19:11:15.544 INFO: view(view_id(NON_PRIM,08651e2d,177) memb

joined {
} left {
} partitioned

)
2018-03-16 19:11:15.544 INFO: view((empty))
2018-03-16 19:11:15.545 INFO: gcomm: closed
2018-03-16 19:11:15.545 INFO: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
2018-03-16 19:11:15.545 INFO: Flow-control interval: [9999999, 9999999]
2018-03-16 19:11:15.545 INFO: Trying to continue unpaused monitor
2018-03-16 19:11:15.545 INFO: Received NON-PRIMARY.
2018-03-16 19:11:15.545 INFO: Shifting PRIMARY -> OPEN (TO: 131462)
2018-03-16 19:11:15.545 INFO: Received self-leave message.
2018-03-16 19:11:15.545 INFO: Flow-control interval: [9999999, 9999999]
2018-03-16 19:11:15.545 INFO: Trying to continue unpaused monitor
2018-03-16 19:11:15.545 INFO: Received SELF-LEAVE. Closing connection.
2018-03-16 19:11:15.545 INFO: Shifting OPEN -> CLOSED (TO: 131462)
2018-03-16 19:11:15.545 INFO: RECV thread exiting 0: Success
2018-03-16 19:11:15.545 INFO: recv_thread() joined.
2018-03-16 19:11:15.545 INFO: Closing replication queue.
2018-03-16 19:11:15.545 INFO: Closing slave action queue.
2018-03-16 19:11:15.545 WARN: Waiting for 2 items to be fetched.