2014-09-21 22:05:40: run.sh: Including /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/conf/conf6.cnf-node1 and /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/conf/conf6.cnf-node2 for conf6 2014-09-21 22:05:40: run.sh: Starting server 1 2014-09-21 22:05:40: run.sh: Starting server with id=1... 2014-09-21 22:05:40: run.sh: Creating server root directory: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1 2014-09-21 22:05:40: run.sh: Creating server temporary directory: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/tmp 2014-09-21 22:05:40: run.sh: Creating server data directory: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data 2014-09-21 22:05:40: run.sh: Calling mysql_install_db Installing MySQL system tables...2014-09-21 22:05:40 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). OK Filling help tables...2014-09-21 22:05:59 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). OK To start mysqld at boot time you have to copy support-files/mysql.server to the right place for your system PLEASE REMEMBER TO SET A PASSWORD FOR THE MySQL root USER ! To do so, start the server, then issue the following commands: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin/mysqladmin -u root password 'new-password' /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin/mysqladmin -u root -h 75fde48ae41e password 'new-password' Alternatively you can run: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin/mysql_secure_installation which will also give you the option of removing the test databases and anonymous user created by default. This is strongly recommended for production servers. See the manual for more instructions. You can start the MySQL daemon with: cd . ; /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin/mysqld_safe & You can test the MySQL daemon with mysql-test-run.pl cd mysql-test ; perl mysql-test-run.pl Please report any problems with Percona XtraDB Cluster 5.6 at https://bugs.launchpad.net/percona-xtradb-cluster/+filebug The latest information about Percona XtraDB Cluster 5.6 is available on the web at http://www.percona.com/doc/percona-xtradb-cluster/5.6/ Support Percona XtraDB Cluster 5.6 by buying support at http://www.percona.com/products/mysql-support WARNING: Default config file /etc/my.cnf exists on the system This file will be read by default by the MySQL server If you do not want to use this, either remove it, or use the --defaults-file argument to mysqld_safe when starting the server Starting /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin//mysqld --defaults-file=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/my.cnf --innodb_flush_method=O_DIRECT --innodb_autoinc_lock_mode=2 --innodb_locks_unsafe_for_binlog=1 --wsrep-slave-threads=2 --innodb_file_per_table --binlog-format=ROW --wsrep-provider=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/lib/libgalera_smm.so --wsrep_cluster_address=gcomm:// --wsrep_sst_receive_address=127.0.0.1:16353 --wsrep_node_incoming_address=127.0.0.1 --wsrep_provider_options=gmcast.listen_addr=tcp://127.0.0.1:25429 --wsrep_sst_method=xtrabackup --wsrep_sst_auth=root:password --wsrep_node_address=127.0.0.1 2014-09-21 22:06:03 0 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16353' 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. 2014-09-21 22:06:03 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). 2014-09-21 22:06:04: run.sh: Made 1 attempts to connect to server 2014-09-21 22:06:04: run.sh: Server with id=1 has been started on port 8565, socket /tmp/xbtemp.ez2357/mysql.sock.ap2396 2014-09-21 22:06:04: run.sh: Sleeping before loading data 2014-09-21 22:06:12: run.sh: Loading sbtest database schema 2014-09-21 22:06:14: run.sh: Loading sbtest database data 2014-09-21 22:06:24: run.sh: Setting password to 'password' 2014-09-21 22:06:24: run.sh: ===> /usr/bin/mysql --defaults-file=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/my.cnf 2014-09-21 22:06:24: run.sh: Starting server 901 2014-09-21 22:06:24: run.sh: Starting server with id=901... 2014-09-21 22:06:24: run.sh: Creating server root directory: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901 2014-09-21 22:06:24: run.sh: Creating server temporary directory: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/tmp 2014-09-21 22:06:24: run.sh: Creating server data directory: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data 2014-09-21 22:06:24: run.sh: Skiping mysql_install_db of node 901 for SST Starting /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin//mysqld --defaults-file=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/my.cnf --innodb_flush_method=O_DIRECT --innodb_autoinc_lock_mode=2 --innodb_locks_unsafe_for_binlog=1 --wsrep-slave-threads=2 --innodb_file_per_table --binlog-format=ROW --wsrep-provider=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/lib/libgalera_smm.so --wsrep_cluster_address=gcomm://127.0.0.1:25429 --wsrep_sst_receive_address=127.0.0.1:19320 --wsrep_node_incoming_address=127.0.0.1 --wsrep_provider_options=gmcast.listen_addr=tcp://127.0.0.1:30900 --wsrep_sst_method=xtrabackup --wsrep_sst_auth=root:password --wsrep_node_address=127.0.0.1 2014-09-21 22:06:25 0 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:19320' 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. 2014-09-21 22:06:25 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). 2014-09-21 22:06:25: run.sh: Made 1 attempts to connect to server 2014-09-21 22:06:26: run.sh: Made 2 attempts to connect to server 2014-09-21 22:06:27: run.sh: Made 3 attempts to connect to server 2014-09-21 22:06:28: run.sh: Made 4 attempts to connect to server 2014-09-21 22:06:29: run.sh: Made 5 attempts to connect to server 2014-09-21 22:06:30: run.sh: Made 6 attempts to connect to server 2014-09-21 22:06:31: run.sh: Made 7 attempts to connect to server 2014-09-21 22:06:32: run.sh: Made 8 attempts to connect to server 2014-09-21 22:06:33: run.sh: Made 9 attempts to connect to server 2014-09-21 22:06:34: run.sh: Made 10 attempts to connect to server 2014-09-21 22:06:35: run.sh: Made 11 attempts to connect to server 2014-09-21 22:06:36: run.sh: Made 12 attempts to connect to server 2014-09-21 22:06:37: run.sh: Made 13 attempts to connect to server 2014-09-21 22:06:38: run.sh: Made 14 attempts to connect to server 2014-09-21 22:06:39: run.sh: Made 15 attempts to connect to server 2014-09-21 22:06:40: run.sh: Made 16 attempts to connect to server 2014-09-21 22:06:41: run.sh: Made 17 attempts to connect to server 2014-09-21 22:06:42: run.sh: Made 18 attempts to connect to server 2014-09-21 22:06:43: run.sh: Made 19 attempts to connect to server 2014-09-21 22:06:44: run.sh: Made 20 attempts to connect to server 2014-09-21 22:06:46: run.sh: Made 21 attempts to connect to server 2014-09-21 22:06:47: run.sh: Made 22 attempts to connect to server 2014-09-21 22:06:48: run.sh: Made 23 attempts to connect to server 2014-09-21 22:06:49: run.sh: Made 24 attempts to connect to server 2014-09-21 22:06:50: run.sh: Made 25 attempts to connect to server 2014-09-21 22:06:51: run.sh: Made 26 attempts to connect to server 2014-09-21 22:06:52: run.sh: Made 27 attempts to connect to server 2014-09-21 22:06:53: run.sh: Made 28 attempts to connect to server 2014-09-21 22:06:54: run.sh: Made 29 attempts to connect to server 2014-09-21 22:06:55: run.sh: Made 30 attempts to connect to server 2014-09-21 22:06:56: run.sh: Server process PID=2749 died. 2014-09-21 22:06:56: run.sh: Can't start the server. Server log (if exists): 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: Error log for server with id: 1 2014-09-21 22:05:40 2422 [Note] WSREP: Read nil XID from storage engines, skipping position init 2014-09-21 22:05:40 2422 [Note] WSREP: wsrep_load(): loading provider library 'none' 2014-09-21 22:05:40 2422 [Note] InnoDB: Using atomics to ref count buffer pool pages 2014-09-21 22:05:40 2422 [Note] InnoDB: The InnoDB memory heap is disabled 2014-09-21 22:05:40 2422 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2014-09-21 22:05:40 2422 [Note] InnoDB: Memory barrier is not used 2014-09-21 22:05:40 2422 [Note] InnoDB: Compressed tables use zlib 1.2.3 2014-09-21 22:05:40 2422 [Note] InnoDB: Using Linux native AIO 2014-09-21 22:05:40 2422 [Note] InnoDB: Using CPU crc32 instructions 2014-09-21 22:05:40 2422 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2014-09-21 22:05:40 2422 [Note] InnoDB: Completed initialization of buffer pool 2014-09-21 22:05:40 2422 [Note] InnoDB: The first specified data file ./ibdata1 did not exist: a new database to be created! 2014-09-21 22:05:40 2422 [Note] InnoDB: Setting file ./ibdata1 size to 12 MB 2014-09-21 22:05:40 2422 [Note] InnoDB: Database physically writes the file full: wait... 2014-09-21 22:05:40 2422 [Note] InnoDB: Setting log file ./ib_logfile101 size to 48 MB 2014-09-21 22:05:41 2422 [Note] InnoDB: Setting log file ./ib_logfile1 size to 48 MB 2014-09-21 22:05:43 2422 [Note] InnoDB: Renaming log file ./ib_logfile101 to ./ib_logfile0 2014-09-21 22:05:43 2422 [Warning] InnoDB: New log files created, LSN=45781 2014-09-21 22:05:43 2422 [Note] InnoDB: Doublewrite buffer not found: creating new 2014-09-21 22:05:43 2422 [Note] InnoDB: Doublewrite buffer created 2014-09-21 22:05:43 2422 [Note] InnoDB: 128 rollback segment(s) are active. 2014-09-21 22:05:43 2422 [Warning] InnoDB: Creating foreign key constraint system tables. 2014-09-21 22:05:43 2422 [Note] InnoDB: Foreign key constraint system tables created 2014-09-21 22:05:43 2422 [Note] InnoDB: Creating tablespace and datafile system tables. 2014-09-21 22:05:43 2422 [Note] InnoDB: Tablespace and datafile system tables created. 2014-09-21 22:05:43 2422 [Note] InnoDB: Waiting for purge to start 2014-09-21 22:05:43 2422 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.20-68.0 started; log sequence number 0 2014-09-21 22:05:44 2422 [Note] RSA private key file not found: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//private_key.pem. Some authentication plugins will not work. 2014-09-21 22:05:44 2422 [Note] RSA public key file not found: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//public_key.pem. Some authentication plugins will not work. 2014-09-21 22:05:57 2422 [Note] WSREP: Service disconnected. 2014-09-21 22:05:58 2422 [Note] WSREP: Some threads may fail to exit. 2014-09-21 22:05:58 2422 [Note] Binlog end 2014-09-21 22:05:58 2422 [Note] InnoDB: FTS optimize thread exiting. 2014-09-21 22:05:58 2422 [Note] InnoDB: Starting shutdown... 2014-09-21 22:05:59 2422 [Note] InnoDB: Shutdown completed; log sequence number 1625977 2014-09-21 22:05:59 2522 [Note] WSREP: Read nil XID from storage engines, skipping position init 2014-09-21 22:05:59 2522 [Note] WSREP: wsrep_load(): loading provider library 'none' 2014-09-21 22:05:59 2522 [Note] InnoDB: Using atomics to ref count buffer pool pages 2014-09-21 22:05:59 2522 [Note] InnoDB: The InnoDB memory heap is disabled 2014-09-21 22:05:59 2522 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2014-09-21 22:05:59 2522 [Note] InnoDB: Memory barrier is not used 2014-09-21 22:05:59 2522 [Note] InnoDB: Compressed tables use zlib 1.2.3 2014-09-21 22:05:59 2522 [Note] InnoDB: Using Linux native AIO 2014-09-21 22:05:59 2522 [Note] InnoDB: Using CPU crc32 instructions 2014-09-21 22:05:59 2522 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2014-09-21 22:05:59 2522 [Note] InnoDB: Completed initialization of buffer pool 2014-09-21 22:05:59 2522 [Note] InnoDB: Highest supported file format is Barracuda. 2014-09-21 22:05:59 2522 [Note] InnoDB: 128 rollback segment(s) are active. 2014-09-21 22:05:59 2522 [Note] InnoDB: Waiting for purge to start 2014-09-21 22:05:59 2522 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.20-68.0 started; log sequence number 1625977 2014-09-21 22:06:00 2522 [Note] RSA private key file not found: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//private_key.pem. Some authentication plugins will not work. 2014-09-21 22:06:00 2522 [Note] RSA public key file not found: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//public_key.pem. Some authentication plugins will not work. 2014-09-21 22:06:00 2522 [Note] WSREP: Service disconnected. 2014-09-21 22:06:01 2522 [Note] WSREP: Some threads may fail to exit. 2014-09-21 22:06:01 2522 [Note] Binlog end 2014-09-21 22:06:01 2522 [Note] InnoDB: FTS optimize thread exiting. 2014-09-21 22:06:01 2522 [Note] InnoDB: Starting shutdown... 2014-09-21 22:06:03 2522 [Note] InnoDB: Shutdown completed; log sequence number 1625987 2014-09-21 22:06:03 2559 [Note] WSREP: Read nil XID from storage engines, skipping position init 2014-09-21 22:06:03 2559 [Note] WSREP: wsrep_load(): loading provider library '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/lib/libgalera_smm.so' 2014-09-21 22:06:03 2559 [Note] WSREP: wsrep_load(): Galera 3.7(r7f44a18) by Codership Oy loaded successfully. 2014-09-21 22:06:03 2559 [Note] WSREP: CRC-32C: using hardware acceleration. 2014-09-21 22:06:03 2559 [Warning] WSREP: Could not open saved state file for reading: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//grastate.dat 2014-09-21 22:06:03 2559 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1 2014-09-21 22:06:03 2559 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 4567; cert.log_conflicts = no; debug = no; 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 = /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//galera.cache; gcache.page_size = 128M; gcache.size = 128M; 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_throttle = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.listen_addr 2014-09-21 22:06:03 2559 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:03 2559 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2014-09-21 22:06:03 2559 [Note] WSREP: wsrep_sst_grab() 2014-09-21 22:06:03 2559 [Note] WSREP: Start replication 2014-09-21 22:06:03 2559 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2014-09-21 22:06:03 2559 [Note] WSREP: protonet asio version 0 2014-09-21 22:06:03 2559 [Note] WSREP: Using CRC-32C for message checksums. 2014-09-21 22:06:03 2559 [Note] WSREP: backend: asio 2014-09-21 22:06:03 2559 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory) 2014-09-21 22:06:03 2559 [Note] WSREP: restore pc from disk failed 2014-09-21 22:06:03 2559 [Note] WSREP: GMCast version 0 2014-09-21 22:06:03 2559 [Note] WSREP: (017a074a, 'tcp://127.0.0.1:25429') listening at tcp://127.0.0.1:25429 2014-09-21 22:06:03 2559 [Note] WSREP: (017a074a, 'tcp://127.0.0.1:25429') multicast: , ttl: 1 2014-09-21 22:06:03 2559 [Note] WSREP: EVS version 0 2014-09-21 22:06:03 2559 [Note] WSREP: PC version 0 2014-09-21 22:06:03 2559 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '' 2014-09-21 22:06:03 2559 [Note] WSREP: start_prim is enabled, turn off pc_recovery 2014-09-21 22:06:03 2559 [Note] WSREP: Node 017a074a state prim 2014-09-21 22:06:03 2559 [Note] WSREP: save pc into disk 2014-09-21 22:06:03 2559 [Note] WSREP: gcomm: connected 2014-09-21 22:06:03 2559 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 2014-09-21 22:06:03 2559 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2014-09-21 22:06:03 2559 [Note] WSREP: Opened channel 'my_wsrep_cluster' 2014-09-21 22:06:03 2559 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1 2014-09-21 22:06:03 2559 [Note] WSREP: Waiting for SST to complete. 2014-09-21 22:06:03 2559 [Note] WSREP: Starting new group from scratch: 017aab57-41fd-11e4-916a-c654141534e5 2014-09-21 22:06:03 2559 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 017ac280-41fd-11e4-ab8c-e6e57ec45651 2014-09-21 22:06:03 2559 [Note] WSREP: STATE EXCHANGE: sent state msg: 017ac280-41fd-11e4-ab8c-e6e57ec45651 2014-09-21 22:06:03 2559 [Note] WSREP: STATE EXCHANGE: got state msg: 017ac280-41fd-11e4-ab8c-e6e57ec45651 from 0 (75fde48ae41e) 2014-09-21 22:06:03 2559 [Note] WSREP: Quorum results: version = 3, component = PRIMARY, conf_id = 0, members = 1/1 (joined/total), act_id = 0, last_appl. = -1, protocols = 0/6/3 (gcs/repl/appl), group UUID = 017aab57-41fd-11e4-916a-c654141534e5 2014-09-21 22:06:03 2559 [Note] WSREP: Flow-control interval: [16, 16] 2014-09-21 22:06:03 2559 [Note] WSREP: Restored state OPEN -> JOINED (0) 2014-09-21 22:06:03 2559 [Note] WSREP: Member 0.0 (75fde48ae41e) synced with group. 2014-09-21 22:06:03 2559 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0) 2014-09-21 22:06:03 2559 [Note] WSREP: New cluster view: global state: 017aab57-41fd-11e4-916a-c654141534e5:0, view# 1: Primary, number of nodes: 1, my index: 0, protocol version 3 2014-09-21 22:06:03 2559 [Note] WSREP: SST complete, seqno: 0 2014-09-21 22:06:03 2559 [Note] Plugin 'FEDERATED' is disabled. 2014-09-21 22:06:03 7f7ed1bb6720 InnoDB: Warning: Using innodb_locks_unsafe_for_binlog is DEPRECATED. This option may be removed in future releases. Please use READ COMMITTED transaction isolation level instead, see http://dev.mysql.com/doc/refman/5.6/en/set-transaction.html. 2014-09-21 22:06:03 2559 [Note] InnoDB: Using atomics to ref count buffer pool pages 2014-09-21 22:06:03 2559 [Note] InnoDB: The InnoDB memory heap is disabled 2014-09-21 22:06:03 2559 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2014-09-21 22:06:03 2559 [Note] InnoDB: Memory barrier is not used 2014-09-21 22:06:03 2559 [Note] InnoDB: Compressed tables use zlib 1.2.3 2014-09-21 22:06:03 2559 [Note] InnoDB: Using Linux native AIO 2014-09-21 22:06:03 2559 [Note] InnoDB: Using CPU crc32 instructions 2014-09-21 22:06:03 2559 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2014-09-21 22:06:03 2559 [Note] InnoDB: Completed initialization of buffer pool 2014-09-21 22:06:03 2559 [Note] InnoDB: Highest supported file format is Barracuda. 2014-09-21 22:06:03 2559 [Note] InnoDB: 128 rollback segment(s) are active. 2014-09-21 22:06:03 2559 [Note] InnoDB: Waiting for purge to start 2014-09-21 22:06:03 2559 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.20-68.0 started; log sequence number 1625987 2014-09-21 22:06:03 2559 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: 01939661-41fd-11e4-aeac-86e22078d498. 2014-09-21 22:06:03 2559 [Note] RSA private key file not found: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//private_key.pem. Some authentication plugins will not work. 2014-09-21 22:06:03 2559 [Note] RSA public key file not found: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data//public_key.pem. Some authentication plugins will not work. 2014-09-21 22:06:03 2559 [Note] Server hostname (bind-address): '*'; port: 8565 2014-09-21 22:06:03 2559 [Note] IPv6 is available. 2014-09-21 22:06:03 2559 [Note] - '::' resolves to '::'; 2014-09-21 22:06:03 2559 [Note] Server socket created on IP: '::'. 2014-09-21 22:06:03 2559 [Note] Event Scheduler: Loaded 0 events 2014-09-21 22:06:03 2559 [Note] /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin//mysqld: ready for connections. Version: '5.6.20-68.0-25.7' socket: '/tmp/xbtemp.ez2357/mysql.sock.ap2396' port: 8565 Percona XtraDB Cluster binary (GPL) 5.6.20-25.7, Revision 886, wsrep_25.7.r4126 2014-09-21 22:06:03 2559 [Note] WSREP: inited wsrep sidno 1 2014-09-21 22:06:03 2559 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2014-09-21 22:06:03 2559 [Note] WSREP: REPL Protocols: 6 (3, 2) 2014-09-21 22:06:03 2559 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:03 2559 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3 2014-09-21 22:06:03 2559 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:03 2559 [Note] WSREP: Synchronized with group, ready for connections 2014-09-21 22:06:03 2559 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2014-09-21 22:06:25 2559 [Note] WSREP: (017a074a, 'tcp://127.0.0.1:25429') turning message relay requesting on, nonlive peers: 2014-09-21 22:06:25 2559 [Note] WSREP: declaring 0e607605 at tcp://127.0.0.1:30900 stable 2014-09-21 22:06:25 2559 [Note] WSREP: Node 017a074a state prim 2014-09-21 22:06:25 2559 [Note] WSREP: save pc into disk 2014-09-21 22:06:25 2559 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2 2014-09-21 22:06:25 2559 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 0ead5fb0-41fd-11e4-b4d0-32feb2b0cd46 2014-09-21 22:06:25 2559 [Note] WSREP: STATE EXCHANGE: sent state msg: 0ead5fb0-41fd-11e4-b4d0-32feb2b0cd46 2014-09-21 22:06:25 2559 [Note] WSREP: STATE EXCHANGE: got state msg: 0ead5fb0-41fd-11e4-b4d0-32feb2b0cd46 from 0 (75fde48ae41e) 2014-09-21 22:06:25 2559 [Note] WSREP: STATE EXCHANGE: got state msg: 0ead5fb0-41fd-11e4-b4d0-32feb2b0cd46 from 1 (75fde48ae41e) 2014-09-21 22:06:25 2559 [Note] WSREP: Quorum results: version = 3, component = PRIMARY, conf_id = 1, members = 1/2 (joined/total), act_id = 7, last_appl. = 4, protocols = 0/6/3 (gcs/repl/appl), group UUID = 017aab57-41fd-11e4-916a-c654141534e5 2014-09-21 22:06:25 2559 [Note] WSREP: Flow-control interval: [23, 23] 2014-09-21 22:06:25 2559 [Note] WSREP: New cluster view: global state: 017aab57-41fd-11e4-916a-c654141534e5:7, view# 2: Primary, number of nodes: 2, my index: 0, protocol version 3 2014-09-21 22:06:25 2559 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2014-09-21 22:06:25 2559 [Note] WSREP: REPL Protocols: 6 (3, 2) 2014-09-21 22:06:25 2559 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:25 2559 [Note] WSREP: Assign initial position for certification: 7, protocol version: 3 2014-09-21 22:06:25 2559 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:25 2559 [Note] WSREP: Member 1.0 (75fde48ae41e) requested state transfer from '*any*'. Selected 0.0 (75fde48ae41e)(SYNCED) as donor. 2014-09-21 22:06:25 2559 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 7) 2014-09-21 22:06:25 2559 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2014-09-21 22:06:25 2559 [Note] WSREP: Running: 'wsrep_sst_xtrabackup --role 'donor' --address '127.0.0.1:19320/xtrabackup_sst' --auth 'root:password' --socket '/tmp/xbtemp.ez2357/mysql.sock.ap2396' --datadir '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data/' --defaults-file '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/my.cnf' '' --gtid '017aab57-41fd-11e4-916a-c654141534e5:7'' 2014-09-21 22:06:25 2559 [Note] WSREP: sst_donor_thread signaled with 0 WSREP_SST: [INFO] Streaming with xbstream (20140921 22:06:25.978) WSREP_SST: [INFO] Using socat as streamer (20140921 22:06:25.979) WSREP_SST: [INFO] Streaming the backup to joiner at 127.0.0.1 19320 (20140921 22:06:25.983) WSREP_SST: [INFO] Evaluating innobackupex --defaults-file=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/my.cnf $INNOEXTRA --galera-info --stream=$sfmt ${TMPDIR} 2>/home/dbennett/logs/innobackup.backup.14092122061411351585.log | pv -f -i 10 -N donor -s 23298048 2>/tmp/progress2-conf6.log | socat -u stdio TCP:127.0.0.1:19320; RC=( ${PIPESTATUS[@]} ) (20140921 22:06:25.988) 2014-09-21 22:06:28 2559 [Note] WSREP: (017a074a, 'tcp://127.0.0.1:25429') turning message relay requesting off 2014-09-21 22:06:29 2559 [Note] WSREP: Provider paused at 017aab57-41fd-11e4-916a-c654141534e5:7 (13) 2014-09-21 22:06:35 2559 [Note] WSREP: resuming provider at 13 2014-09-21 22:06:35 2559 [Note] WSREP: Provider resumed. WSREP_SST: [INFO] NOTE: Donor-Transfer took 10 seconds (20140921 22:06:35.862) 2014-09-21 22:06:35 2559 [Note] WSREP: 0.0 (75fde48ae41e): State transfer to 1.0 (75fde48ae41e) complete. 2014-09-21 22:06:35 2559 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 7) 2014-09-21 22:06:35 2559 [Note] WSREP: Member 0.0 (75fde48ae41e) synced with group. 2014-09-21 22:06:35 2559 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 7) 2014-09-21 22:06:35 2559 [Note] WSREP: Synchronized with group, ready for connections 2014-09-21 22:06:35 2559 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. WSREP_SST: [INFO] Total time on donor: 10 seconds (20140921 22:06:35.864) 2014-09-21 22:06:49 2559 [Note] WSREP: forgetting 0e607605 (tcp://127.0.0.1:30900) 2014-09-21 22:06:49 2559 [Note] WSREP: Node 017a074a state prim 2014-09-21 22:06:49 2559 [Note] WSREP: save pc into disk 2014-09-21 22:06:49 2559 [Note] WSREP: forgetting 0e607605 (tcp://127.0.0.1:30900) 2014-09-21 22:06:49 2559 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1 2014-09-21 22:06:49 2559 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 1cd7dbb8-41fd-11e4-90ae-1a0a39278e9c 2014-09-21 22:06:49 2559 [Note] WSREP: STATE EXCHANGE: sent state msg: 1cd7dbb8-41fd-11e4-90ae-1a0a39278e9c 2014-09-21 22:06:49 2559 [Note] WSREP: STATE EXCHANGE: got state msg: 1cd7dbb8-41fd-11e4-90ae-1a0a39278e9c from 0 (75fde48ae41e) 2014-09-21 22:06:49 2559 [Note] WSREP: Quorum results: version = 3, component = PRIMARY, conf_id = 2, members = 1/1 (joined/total), act_id = 7, last_appl. = 4, protocols = 0/6/3 (gcs/repl/appl), group UUID = 017aab57-41fd-11e4-916a-c654141534e5 2014-09-21 22:06:49 2559 [Note] WSREP: Flow-control interval: [16, 16] 2014-09-21 22:06:49 2559 [Note] WSREP: New cluster view: global state: 017aab57-41fd-11e4-916a-c654141534e5:7, view# 3: Primary, number of nodes: 1, my index: 0, protocol version 3 2014-09-21 22:06:49 2559 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2014-09-21 22:06:49 2559 [Note] WSREP: REPL Protocols: 6 (3, 2) 2014-09-21 22:06:49 2559 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:49 2559 [Note] WSREP: Assign initial position for certification: 7, protocol version: 3 2014-09-21 22:06:49 2559 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:54 2559 [Note] WSREP: cleaning up 0e607605 (tcp://127.0.0.1:30900) 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: Error log for server with id: 901 2014-09-21 22:06:25 2749 [Note] WSREP: Read nil XID from storage engines, skipping position init 2014-09-21 22:06:25 2749 [Note] WSREP: wsrep_load(): loading provider library '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/lib/libgalera_smm.so' 2014-09-21 22:06:25 2749 [Note] WSREP: wsrep_load(): Galera 3.7(r7f44a18) by Codership Oy loaded successfully. 2014-09-21 22:06:25 2749 [Note] WSREP: CRC-32C: using hardware acceleration. 2014-09-21 22:06:25 2749 [Warning] WSREP: Could not open saved state file for reading: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data//grastate.dat 2014-09-21 22:06:25 2749 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1 2014-09-21 22:06:25 2749 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 4567; cert.log_conflicts = no; debug = no; 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 = /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data//galera.cache; gcache.page_size = 128M; gcache.size = 128M; 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_throttle = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.listen_a 2014-09-21 22:06:25 2749 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:25 2749 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2014-09-21 22:06:25 2749 [Note] WSREP: wsrep_sst_grab() 2014-09-21 22:06:25 2749 [Note] WSREP: Start replication 2014-09-21 22:06:25 2749 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2014-09-21 22:06:25 2749 [Note] WSREP: protonet asio version 0 2014-09-21 22:06:25 2749 [Note] WSREP: Using CRC-32C for message checksums. 2014-09-21 22:06:25 2749 [Note] WSREP: backend: asio 2014-09-21 22:06:25 2749 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory) 2014-09-21 22:06:25 2749 [Note] WSREP: restore pc from disk failed 2014-09-21 22:06:25 2749 [Note] WSREP: GMCast version 0 2014-09-21 22:06:25 2749 [Note] WSREP: (0e607605, 'tcp://127.0.0.1:30900') listening at tcp://127.0.0.1:30900 2014-09-21 22:06:25 2749 [Note] WSREP: (0e607605, 'tcp://127.0.0.1:30900') multicast: , ttl: 1 2014-09-21 22:06:25 2749 [Note] WSREP: EVS version 0 2014-09-21 22:06:25 2749 [Note] WSREP: PC version 0 2014-09-21 22:06:25 2749 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:25429' 2014-09-21 22:06:25 2749 [Note] WSREP: (0e607605, 'tcp://127.0.0.1:30900') turning message relay requesting on, nonlive peers: 2014-09-21 22:06:25 2749 [Note] WSREP: gcomm: connected 2014-09-21 22:06:25 2749 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 2014-09-21 22:06:25 2749 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2014-09-21 22:06:25 2749 [Note] WSREP: Opened channel 'my_wsrep_cluster' 2014-09-21 22:06:25 2749 [Note] WSREP: Waiting for SST to complete. 2014-09-21 22:06:25 2749 [Note] WSREP: declaring 017a074a at tcp://127.0.0.1:25429 stable 2014-09-21 22:06:25 2749 [Note] WSREP: Node 017a074a state prim 2014-09-21 22:06:25 2749 [Note] WSREP: save pc into disk 2014-09-21 22:06:25 2749 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2 2014-09-21 22:06:25 2749 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID. 2014-09-21 22:06:25 2749 [Note] WSREP: STATE EXCHANGE: sent state msg: 0ead5fb0-41fd-11e4-b4d0-32feb2b0cd46 2014-09-21 22:06:25 2749 [Note] WSREP: STATE EXCHANGE: got state msg: 0ead5fb0-41fd-11e4-b4d0-32feb2b0cd46 from 0 (75fde48ae41e) 2014-09-21 22:06:25 2749 [Note] WSREP: STATE EXCHANGE: got state msg: 0ead5fb0-41fd-11e4-b4d0-32feb2b0cd46 from 1 (75fde48ae41e) 2014-09-21 22:06:25 2749 [Note] WSREP: Quorum results: version = 3, component = PRIMARY, conf_id = 1, members = 1/2 (joined/total), act_id = 7, last_appl. = -1, protocols = 0/6/3 (gcs/repl/appl), group UUID = 017aab57-41fd-11e4-916a-c654141534e5 2014-09-21 22:06:25 2749 [Note] WSREP: Flow-control interval: [23, 23] 2014-09-21 22:06:25 2749 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 7) 2014-09-21 22:06:25 2749 [Note] WSREP: State transfer required: Group state: 017aab57-41fd-11e4-916a-c654141534e5:7 Local state: 00000000-0000-0000-0000-000000000000:-1 2014-09-21 22:06:25 2749 [Note] WSREP: New cluster view: global state: 017aab57-41fd-11e4-916a-c654141534e5:7, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3 2014-09-21 22:06:25 2749 [Warning] WSREP: Gap in state sequence. Need state transfer. 2014-09-21 22:06:25 2749 [Note] WSREP: Running: 'wsrep_sst_xtrabackup --role 'joiner' --address '127.0.0.1:19320' --auth 'root:password' --datadir '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data/' --defaults-file '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/my.cnf' --parent '2749' '' ' WSREP_SST: [INFO] Streaming with xbstream (20140921 22:06:25.721) WSREP_SST: [INFO] Using socat as streamer (20140921 22:06:25.722) WSREP_SST: [INFO] Evaluating socat -u TCP-LISTEN:19320,reuseaddr stdio | pv -f -i 10 -N joiner 2>/tmp/progress1-conf6.log | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20140921 22:06:25.728) 2014-09-21 22:06:25 2749 [Note] WSREP: Prepared SST request: xtrabackup|127.0.0.1:19320/xtrabackup_sst 2014-09-21 22:06:25 2749 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2014-09-21 22:06:25 2749 [Note] WSREP: REPL Protocols: 6 (3, 2) 2014-09-21 22:06:25 2749 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:25 2749 [Note] WSREP: Assign initial position for certification: 7, protocol version: 3 2014-09-21 22:06:25 2749 [Note] WSREP: Service thread queue flushed. 2014-09-21 22:06:25 2749 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (017aab57-41fd-11e4-916a-c654141534e5): 1 (Operation not permitted) at galera/src/replicator_str.cpp:prepare_for_IST():455. IST will be unavailable. 2014-09-21 22:06:25 2749 [Note] WSREP: Member 1.0 (75fde48ae41e) requested state transfer from '*any*'. Selected 0.0 (75fde48ae41e)(SYNCED) as donor. 2014-09-21 22:06:25 2749 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 7) 2014-09-21 22:06:25 2749 [Note] WSREP: Requesting state transfer: success, donor: 0 2014-09-21 22:06:28 2749 [Note] WSREP: (0e607605, 'tcp://127.0.0.1:30900') turning message relay requesting off 2014-09-21 22:06:35 2749 [Note] WSREP: 0.0 (75fde48ae41e): State transfer to 1.0 (75fde48ae41e) complete. 2014-09-21 22:06:35 2749 [Note] WSREP: Member 0.0 (75fde48ae41e) synced with group. WSREP_SST: [INFO] NOTE: Joiner-Recv-Unencrypted took 18 seconds (20140921 22:06:43.494) WSREP_SST: [INFO] Proceeding with SST (20140921 22:06:43.500) WSREP_SST: [INFO] Removing existing ib_logfile files (20140921 22:06:43.503) WSREP_SST: [INFO] Index compaction detected (20140921 22:06:43.509) WSREP_SST: [INFO] Rebuilding during prepare with 12 threads (20140921 22:06:43.517) WSREP_SST: [INFO] Preparing the backup at /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data/ (20140921 22:06:43.521) WSREP_SST: [INFO] Evaluating innobackupex --defaults-file=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/my.cnf --apply-log $rebuildcmd ${DATA} &>/home/dbennett/logs/innobackup.14092122061411351585.prepare.log (20140921 22:06:43.525) WSREP_SST: [ERROR] Cleanup after exit with status:1 (20140921 22:06:46.433) WSREP_SST: [INFO] Removing the sst_in_progress file (20140921 22:06:46.435) 2014-09-21 22:06:46 2749 [ERROR] WSREP: Process completed with error: wsrep_sst_xtrabackup --role 'joiner' --address '127.0.0.1:19320' --auth 'root:password' --datadir '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data/' --defaults-file '/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/my.cnf' --parent '2749' '' : 1 (Operation not permitted) 2014-09-21 22:06:46 2749 [ERROR] WSREP: Failed to read uuid:seqno from joiner script. 2014-09-21 22:06:46 2749 [ERROR] WSREP: SST failed: 1 (Operation not permitted) 2014-09-21 22:06:46 2749 [ERROR] Aborting 2014-09-21 22:06:48 2749 [Note] WSREP: Closing send monitor... 2014-09-21 22:06:48 2749 [Note] WSREP: Closed send monitor. 2014-09-21 22:06:48 2749 [Note] WSREP: gcomm: terminating thread 2014-09-21 22:06:48 2749 [Note] WSREP: gcomm: joining thread 2014-09-21 22:06:48 2749 [Note] WSREP: gcomm: closing backend 2014-09-21 22:06:49 2749 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1 2014-09-21 22:06:49 2749 [Note] WSREP: gcomm: closed 2014-09-21 22:06:49 2749 [Note] WSREP: Flow-control interval: [16, 16] 2014-09-21 22:06:49 2749 [Note] WSREP: Received NON-PRIMARY. 2014-09-21 22:06:49 2749 [Note] WSREP: Shifting JOINER -> OPEN (TO: 7) 2014-09-21 22:06:49 2749 [Note] WSREP: Received self-leave message. 2014-09-21 22:06:49 2749 [Note] WSREP: Flow-control interval: [0, 0] 2014-09-21 22:06:49 2749 [Note] WSREP: Received SELF-LEAVE. Closing connection. 2014-09-21 22:06:49 2749 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 7) 2014-09-21 22:06:49 2749 [Note] WSREP: RECV thread exiting 0: Success 2014-09-21 22:06:49 2749 [Note] WSREP: recv_thread() joined. 2014-09-21 22:06:49 2749 [Note] WSREP: Closing replication queue. 2014-09-21 22:06:49 2749 [Note] WSREP: Closing slave action queue. 2014-09-21 22:06:49 2749 [Note] WSREP: Service disconnected. 2014-09-21 22:06:49 2749 [Note] WSREP: rollbacker thread exiting 2014-09-21 22:06:50 2749 [Note] WSREP: Some threads may fail to exit. 2014-09-21 22:06:50 2749 [Note] Binlog end 2014-09-21 22:06:50 2749 [Note] /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/bin//mysqld: Shutdown complete Error in my_thread_global_end(): 1 threads didn't exit 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: Innobackupex log for server with id: 1 cat: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data/innobackup*.log: No such file or directory 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: Innobackupex log for server with id: 901 cat: /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data/innobackup*.log: No such file or directory 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: Configuration for server with id: 1 [mysqld] socket=/tmp/xbtemp.ez2357/mysql.sock.ap2396 port=8565 server-id=1 basedir=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64 datadir=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data tmpdir=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/tmp log-error=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var1/data/mysqld1.err pid-file=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/mysqld1.pid !include /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/conf/conf6.cnf-node1 [client] socket=/tmp/xbtemp.ez2357/mysql.sock.ap2396 user=root 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: ---------------- 2014-09-21 22:06:56: run.sh: Configuration for server with id: 901 [mysqld] socket=/tmp/xbtemp.ez2357/mysql.sock.Ou2726 port=18067 server-id=901 basedir=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64 datadir=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data tmpdir=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/tmp log-error=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/var901/data/mysqld901.err pid-file=/home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/var/w1/mysqld901.pid !include /home/dbennett/work/2/Percona-XtraDB-Cluster-5.6.20-rel68.0-25.7.886.Linux.x86_64/percona-xtradb-cluster-tests/conf/conf6.cnf-node2 [client] socket=/tmp/xbtemp.ez2357/mysql.sock.Ou2726 user=root 2014-09-21 22:06:56: run.sh: ----------------