2014-07-31 20:10:51 31574 [Note] WSREP: inited wsrep sidno 2
2014-07-31 20:10:51 31574 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-07-31 20:10:51 31574 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-07-31 20:10:51 31574 [Note] WSREP: Service thread queue flushed.
2014-07-31 20:10:51 31574 [Note] WSREP: Assign initial position for certification: 3878257, protocol version: 3
2014-07-31 20:10:51 31574 [Note] WSREP: Service thread queue flushed.
2014-07-31 20:10:51 31574 [Note] WSREP: Synchronized with group, ready for connections
2014-07-31 20:10:51 31574 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-07-31 20:11:21 31574 [Warning] Storing MySQL user name or password information in the master info repository is not secure and is therefore not recommended. Please consider using the USER and PASSWORD connection options for START SLAVE; see the 'START SLAVE Syntax' in the MySQL Manual for more information.
2014-07-31 20:11:21 31574 [Warning] Slave SQL: If a crash happens this configuration does not guarantee that the relay log info will be consistent, Error_code: 0
2014-07-31 20:11:21 31574 [Note] WSREP: ready state reached
2014-07-31 20:11:21 31574 [Note] Slave SQL thread initialized, starting replication in log 'mysql-bin.235894' at position 79854239, relay log './mysqld-relay-bin.001643' position: 79854398
2014-07-31 20:11:21 31574 [Note] Slave I/O thread: connected to master 'replication@172.20.2.3:3306',replication started in log 'mysql-bin.235895' at position 52096169
2014-07-31 20:11:21 31574 [Warning] Slave I/O: Notifying master by SET @master_binlog_checksum= @@global.binlog_checksum failed with error: Unknown system variable 'binlog_checksum', Error_code: 1193
2014-07-31 20:11:21 31574 [Warning] Slave I/O: Unknown system variable 'SERVER_UUID' on master. A probable cause is that the variable is not supported on the master (version: 5.1.49-3-log), even though it is on the slave (version: 5.6.19-67.0-56-log), Error_code: 1193
2014-07-31 20:11:55 31574 [Note] WSREP: (ff66a051, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers:
2014-07-31 20:11:55 31574 [Note] WSREP: declaring 27ef482c at tcp://172.20.0.9:4567 stable
2014-07-31 20:11:55 31574 [Note] WSREP: Node ff66a051 state prim
2014-07-31 20:11:55 31574 [Note] WSREP: save pc into disk
2014-07-31 20:11:55 31574 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-07-31 20:11:55 31574 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-07-31 20:11:56 31574 [Note] WSREP: STATE EXCHANGE: sent state msg: 283c0b18-18de-11e4-9378-7b8ab369abcd
2014-07-31 20:11:56 31574 [Note] WSREP: STATE EXCHANGE: got state msg: 283c0b18-18de-11e4-9378-7b8ab369abcd from 0 (backenddb-02)
2014-07-31 20:11:56 31574 [Note] WSREP: STATE EXCHANGE: got state msg: 283c0b18-18de-11e4-9378-7b8ab369abcd from 1 (backenddb-01)
2014-07-31 20:11:56 31574 [Note] WSREP: Quorum results:
version = 3,
component = PRIMARY,
conf_id = 1,
members = 1/2 (joined/total),
act_id = 3878257,
last_appl. = 0,
protocols = 0/6/3 (gcs/repl/appl),
group UUID = a296889f-13fd-11e4-9807-f2390d3c4de7
2014-07-31 20:11:56 31574 [Note] WSREP: Flow-control interval: [23, 23]
2014-07-31 20:11:56 31574 [Note] WSREP: New cluster view: global state: a296889f-13fd-11e4-9807-f2390d3c4de7:3878257, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-07-31 20:11:56 31574 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-07-31 20:11:56 31574 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-07-31 20:11:56 31574 [Note] WSREP: Service thread queue flushed.
2014-07-31 20:11:56 31574 [Note] WSREP: Assign initial position for certification: 3878257, protocol version: 3
2014-07-31 20:11:56 31574 [Note] WSREP: Service thread queue flushed.
2014-07-31 20:11:58 31574 [Note] WSREP: (ff66a051, 'tcp://0.0.0.0:4567') turning message relay requesting off
2014-07-31 20:11:59 31574 [Note] WSREP: Member 0.0 (backenddb-02) requested state transfer from '*any*'. Selected 1.0 (backenddb-01)(SYNCED) as donor.
2014-07-31 20:11:59 31574 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 3878257)
2014-07-31 20:11:59 31574 [Note] WSREP: IST request: a296889f-13fd-11e4-9807-f2390d3c4de7:3702665-3878257|tcp://172.20.0.9:4568
2014-07-31 20:11:59 31574 [Note] WSREP: IST first seqno 3702666 not found from cache, falling back to SST
2014-07-31 20:11:59 31574 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-07-31 20:11:59 31574 [Note] WSREP: Running: 'wsrep_sst_xtrabackup-v2 --role 'donor' --address '172.20.0.9:4444/xtrabackup_sst' --auth 'sstuser:s3cretPass' --socket '/var/run/mysqld/mysqld.sock' --datadir '/var/lib/mysql/' --defaults-file '/etc/mysql/my.cnf' '' --gtid 'a296889f-13fd-11e4-9807-f2390d3c4de7:3878257''
2014-07-31 20:11:59 31574 [Note] WSREP: sst_donor_thread signaled with 0
WSREP_SST: [INFO] Streaming with xbstream (20140731 20:11:59.315)
WSREP_SST: [INFO] Using socat as streamer (20140731 20:11:59.317)
WSREP_SST: [INFO] Using /tmp/tmp.6i6qzI1XGo as innobackupex temporary directory (20140731 20:11:59.599)
WSREP_SST: [INFO] Streaming GTID file before SST (20140731 20:11:59.605)
WSREP_SST: [INFO] Evaluating xbstream -c ${INFO_FILE} | socat -u stdio TCP:172.20.0.9:4444; RC=( ${PIPESTATUS[@]} ) (20140731 20:11:59.608)
WSREP_SST: [INFO] Sleeping before data transfer for SST (20140731 20:11:59.614)
WSREP_SST: [INFO] Streaming the backup to joiner at 172.20.0.9 4444 (20140731 20:12:09.617)
WSREP_SST: [INFO] Evaluating innobackupex --defaults-file=/etc/mysql/my.cnf --no-version-check $tmpopts $INNOEXTRA --galera-info --stream=$sfmt $itmpdir 2>${DATA}/innobackup.backup.log | socat -u stdio TCP:172.20.0.9:4444; RC=( ${PIPESTATUS[@]} ) (20140731 20:12:09.621)
2014/07/31 20:12:11 socat[32352] E write(3, 0x14732b0, 8192): Broken pipe
WSREP_SST: [ERROR] innobackupex finished with error: 1. Check /var/lib/mysql//innobackup.backup.log (20140731 20:12:11.888)
WSREP_SST: [ERROR] Cleanup after exit with status:22 (20140731 20:12:11.890)
WSREP_SST: [INFO] Cleaning up temporary directories (20140731 20:12:11.893)
2014-07-31 20:12:11 31574 [ERROR] WSREP: Failed to read from: wsrep_sst_xtrabackup-v2 --role 'donor' --address '172.20.0.9:4444/xtrabackup_sst' --auth 'sstuser:s3cretPass' --socket '/var/run/mysqld/mysqld.sock' --datadir '/var/lib/mysql/' --defaults-file '/etc/mysql/my.cnf' '' --gtid 'a296889f-13fd-11e4-9807-f2390d3c4de7:3878257'
2014-07-31 20:12:11 31574 [ERROR] WSREP: Process completed with error: wsrep_sst_xtrabackup-v2 --role 'donor' --address '172.20.0.9:4444/xtrabackup_sst' --auth 'sstuser:s3cretPass' --socket '/var/run/mysqld/mysqld.sock' --datadir '/var/lib/mysql/' --defaults-file '/etc/mysql/my.cnf' '' --gtid 'a296889f-13fd-11e4-9807-f2390d3c4de7:3878257': 22 (Invalid argument)
2014-07-31 20:12:11 31574 [ERROR] WSREP: Command did not run: wsrep_sst_xtrabackup-v2 --role 'donor' --address '172.20.0.9:4444/xtrabackup_sst' --auth 'sstuser:s3cretPass' --socket '/var/run/mysqld/mysqld.sock' --datadir '/var/lib/mysql/' --defaults-file '/etc/mysql/my.cnf' '' --gtid 'a296889f-13fd-11e4-9807-f2390d3c4de7:3878257'
2014-07-31 20:12:11 31574 [Warning] WSREP: 1.0 (backenddb-01): State transfer to 0.0 (backenddb-02) failed: -22 (Invalid argument)
2014-07-31 20:12:11 31574 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 3879661)
2014-07-31 20:12:11 31574 [Note] WSREP: Member 1.0 (backenddb-01) synced with group.
2014-07-31 20:12:11 31574 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 3879661)
2014-07-31 20:12:11 31574 [Note] WSREP: Synchronized with group, ready for connections
2014-07-31 20:12:11 31574 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-07-31 20:12:12 31574 [Note] WSREP: forgetting 27ef482c (tcp://172.20.0.9:4567)
2014-07-31 20:12:12 31574 [Note] WSREP: Node ff66a051 state prim
2014-07-31 20:12:12 31574 [Note] WSREP: save pc into disk
2014-07-31 20:12:12 31574 [Note] WSREP: forgetting 27ef482c (tcp://172.20.0.9:4567)
2014-07-31 20:12:12 31574 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2014-07-31 20:12:12 31574 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 32067541-18de-11e4-ae17-bb9f72bd71e4
2014-07-31 20:12:12 31574 [Note] WSREP: STATE EXCHANGE: sent state msg: 32067541-18de-11e4-ae17-bb9f72bd71e4
2014-07-31 20:12:12 31574 [Note] WSREP: STATE EXCHANGE: got state msg: 32067541-18de-11e4-ae17-bb9f72bd71e4 from 0 (backenddb-01)
2014-07-31 20:12:12 31574 [Note] WSREP: Quorum results:
version = 3,
component = PRIMARY,
conf_id = 2,
members = 1/1 (joined/total),
act_id = 3879661,
last_appl. = 3879607,
protocols = 0/6/3 (gcs/repl/appl),
group UUID = a296889f-13fd-11e4-9807-f2390d3c4de7
2014-07-31 20:12:12 31574 [Note] WSREP: Flow-control interval: [16, 16]
2014-07-31 20:12:12 31574 [Note] WSREP: New cluster view: global state: a296889f-13fd-11e4-9807-f2390d3c4de7:3879661, view# 3: Primary, number of nodes: 1, my index: 0, protocol version 3