CURRENT_TEST: galera.galera_binlog_checksum
2014-10-09 10:24:30 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2014-10-09 10:24:30 0 [Warning] WSREP: option --wsrep-casual-reads is deprecated
2014-10-09 10:24:30 40716 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-10-09 10:24:30 40716 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:24:30 40716 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:24:30 40716 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:24:30 40716 [Warning] WSREP: Could not open saved state file for reading: /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data//grastate.dat
2014-10-09 10:24:30 40716 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:30 40716 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; 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/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = f
2014-10-09 10:24:30 40716 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:30 40716 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2014-10-09 10:24:30 40716 [Note] WSREP: wsrep_sst_grab()
2014-10-09 10:24:30 40716 [Note] WSREP: Start replication
2014-10-09 10:24:30 40716 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:30 40716 [Note] WSREP: protonet asio version 0
2014-10-09 10:24:30 40716 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:24:30 40716 [Note] WSREP: backend: asio
2014-10-09 10:24:30 40716 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:24:30 40716 [Note] WSREP: restore pc from disk failed
2014-10-09 10:24:30 40716 [Note] WSREP: GMCast version 0
2014-10-09 10:24:30 40716 [Note] WSREP: (4f361f2b, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:24:30 40716 [Note] WSREP: (4f361f2b, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:24:30 40716 [Note] WSREP: EVS version 0
2014-10-09 10:24:30 40716 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:24:30 40716 [Note] WSREP: (4f361f2b, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:24:31 40716 [Note] WSREP: declaring 4ef76d24 at tcp://127.0.0.1:13001 stable
2014-10-09 10:24:31 40716 [Note] WSREP: Node 4ef76d24 state prim
2014-10-09 10:24:31 40716 [Note] WSREP: save pc into disk
2014-10-09 10:24:31 40716 [Note] WSREP: gcomm: connected
2014-10-09 10:24:31 40716 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:24:31 40716 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:24:31 40716 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:24:31 40716 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:24:31 40716 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:24:31 40716 [Note] WSREP: Waiting for SST to complete.
2014-10-09 10:24:31 40716 [Note] WSREP: STATE EXCHANGE: sent state msg: 4f83a701-4f85-11e4-80ae-639e75019a81
2014-10-09 10:24:31 40716 [Note] WSREP: STATE EXCHANGE: got state msg: 4f83a701-4f85-11e4-80ae-639e75019a81 from 0 (localhost.localdomain)
2014-10-09 10:24:31 40716 [Note] WSREP: STATE EXCHANGE: got state msg: 4f83a701-4f85-11e4-80ae-639e75019a81 from 1 (localhost.localdomain)
2014-10-09 10:24:31 40716 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 1,
	members    = 1/2 (joined/total),
	act_id     = 0,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 4ef7e266-4f85-11e4-99a7-26c302c0406d
2014-10-09 10:24:31 40716 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:24:31 40716 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2014-10-09 10:24:31 40716 [Note] WSREP: State transfer required: 
	Group state: 4ef7e266-4f85-11e4-99a7-26c302c0406d:0
	Local state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:31 40716 [Note] WSREP: New cluster view: global state: 4ef7e266-4f85-11e4-99a7-26c302c0406d:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:24:31 40716 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:24:31 40716 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1' --auth '' --datadir '/home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/' --defaults-file '/home/philips/git/codership-mysql/mysql-test/var/my.cnf' --parent '40716'  '' '
/home/philips/git/codership-mysql/scripts/wsrep_sst_common: line 98: my_print_defaults: command not found
/home/philips/git/codership-mysql/scripts/wsrep_sst_rsync: line 94: /home/philips/git/codership-mysql/scripts/my_print_defaults: No such file or directory
2014-10-09 10:24:31 40716 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:4444/rsync_sst
2014-10-09 10:24:31 40716 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:24:31 40716 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:24:31 40716 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:31 40716 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2014-10-09 10:24:31 40716 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:31 40716 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (4ef7e266-4f85-11e4-99a7-26c302c0406d): 1 (Operation not permitted)
	 at galera/src/replicator_str.cpp:prepare_for_IST():456. IST will be unavailable.
2014-10-09 10:24:31 40716 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:24:31 40716 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2014-10-09 10:24:31 40716 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:24:32 40716 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:24:32 40716 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
WSREP_SST: [INFO] Joiner cleanup. (20141009 10:24:32.750)
WSREP_SST: [INFO] Joiner cleanup done. (20141009 10:24:33.300)
2014-10-09 10:24:33 40716 [Note] WSREP: SST complete, seqno: 0
2014-10-09 10:24:33 40716 [Note] Plugin 'FEDERATED' is disabled.
2014-10-09 10:24:33 7ff174208780 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2014-10-09 10:24:33 40716 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-10-09 10:24:33 40716 [Note] InnoDB: The InnoDB memory heap is disabled
2014-10-09 10:24:33 40716 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-10-09 10:24:33 40716 [Note] InnoDB: Memory barrier is not used
2014-10-09 10:24:33 40716 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-10-09 10:24:33 40716 [Note] InnoDB: Using Linux native AIO
2014-10-09 10:24:33 40716 [Note] InnoDB: Using CPU crc32 instructions
2014-10-09 10:24:33 40716 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2014-10-09 10:24:33 40716 [Note] InnoDB: Completed initialization of buffer pool
2014-10-09 10:24:33 40716 [Note] InnoDB: Highest supported file format is Barracuda.
2014-10-09 10:24:33 40716 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1625977
2014-10-09 10:24:33 40716 [Note] InnoDB: Database was not shutdown normally!
2014-10-09 10:24:33 40716 [Note] InnoDB: Starting crash recovery.
2014-10-09 10:24:33 40716 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-10-09 10:24:33 40716 [Note] InnoDB: Restoring possible half-written data pages 
2014-10-09 10:24:33 40716 [Note] InnoDB: from the doublewrite buffer...
InnoDB: Doing recovery: scanned up to log sequence number 1626167
2014-10-09 10:24:33 40716 [Note] InnoDB: 128 rollback segment(s) are active.
2014-10-09 10:24:33 40716 [Note] InnoDB: Waiting for purge to start
2014-10-09 10:24:33 40716 [Note] InnoDB: 5.6.21 started; log sequence number 1626167
2014-10-09 10:24:33 40716 [Warning] /home/philips/git/codership-mysql/sql/mysqld: unknown variable 'loose-debug-sync-timeout=600'
2014-10-09 10:24:33 40716 [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: 50f4d226-4f85-11e4-89cd-000c29102922.
2014-10-09 10:24:33 40716 [Note] Server hostname (bind-address): '*'; port: 13003
2014-10-09 10:24:33 40716 [Note] IPv6 is available.
2014-10-09 10:24:33 40716 [Note]   - '::' resolves to '::';
2014-10-09 10:24:33 40716 [Note] Server socket created on IP: '::'.
2014-10-09 10:24:33 40716 [Note] Event Scheduler: Loaded 0 events
2014-10-09 10:24:33 40716 [Note] WSREP: Signalling provider to continue.
2014-10-09 10:24:33 40716 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:24:33 40716 [Note] WSREP: SST received: 4ef7e266-4f85-11e4-99a7-26c302c0406d:0
2014-10-09 10:24:33 40716 [Note] /home/philips/git/codership-mysql/sql/mysqld: ready for connections.
Version: '5.6.21-log'  socket: '/home/philips/git/codership-mysql/mysql-test/var/tmp/mysqld.2.sock'  port: 13003  , wsrep_25.6.r4149
2014-10-09 10:24:33 40716 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:24:33 40716 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2014-10-09 10:24:33 40716 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:24:33 40716 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2014-10-09 10:24:33 40716 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:24:33 40716 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:24:33 40716 [Note] WSREP: (4f361f2b, 'tcp://0.0.0.0:13004') turning message relay requesting off
2014-10-09 10:24:34 40716 [Note] /home/philips/git/codership-mysql/sql/mysqld: Normal shutdown

2014-10-09 10:24:34 40716 [Note] WSREP: Stop replication
2014-10-09 10:24:34 40716 [Note] WSREP: Closing send monitor...
2014-10-09 10:24:34 40716 [Note] WSREP: Closed send monitor.
2014-10-09 10:24:34 40716 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:24:34 40716 [Note] WSREP: gcomm: joining thread
2014-10-09 10:24:34 40716 [Note] WSREP: gcomm: closing backend
2014-10-09 10:24:35 40716 [Note] WSREP: forgetting 4ef76d24 (tcp://127.0.0.1:13001)
2014-10-09 10:24:35 40716 [Note] WSREP: Node 4f361f2b state prim
2014-10-09 10:24:35 40716 [Note] WSREP: save pc into disk
2014-10-09 10:24:35 40716 [Note] WSREP: forgetting 4ef76d24 (tcp://127.0.0.1:13001)
2014-10-09 10:24:35 40716 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:24:35 40716 [Note] WSREP: gcomm: closed
2014-10-09 10:24:35 40716 [Warning] WSREP: 0x19fc960 down context(s) not set
2014-10-09 10:24:35 40716 [Warning] WSREP: Failed to send state UUID: -107 (Transport endpoint is not connected)
2014-10-09 10:24:35 40716 [Note] WSREP: Received self-leave message.
2014-10-09 10:24:35 40716 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:24:35 40716 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:24:35 40716 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 6)
2014-10-09 10:24:35 40716 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:24:35 40716 [Note] WSREP: recv_thread() joined.
2014-10-09 10:24:35 40716 [Note] WSREP: Closing replication queue.
2014-10-09 10:24:35 40716 [Note] WSREP: Closing slave action queue.
2014-10-09 10:24:35 40716 [Note] WSREP: New cluster view: global state: 4ef7e266-4f85-11e4-99a7-26c302c0406d:6, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:24:35 40716 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:24:35 40716 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:24:37 40716 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:24:37 40716 [Note] Giving 0 client threads a chance to die gracefully
2014-10-09 10:24:37 40716 [Note] Event Scheduler: Purging the queue. 0 events
2014-10-09 10:24:37 40716 [Note] Shutting down slave threads
2014-10-09 10:24:37 40716 [Note] Forcefully disconnecting 0 remaining clients
2014-10-09 10:24:37 40716 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:24:37 40716 [Note] WSREP: mon: entered 6 oooe fraction 0 oool fraction 0
2014-10-09 10:24:37 40716 [Note] WSREP: mon: entered 6 oooe fraction 0 oool fraction 0
2014-10-09 10:24:37 40716 [Note] WSREP: mon: entered 10 oooe fraction 0 oool fraction 0.1
2014-10-09 10:24:37 40716 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:24:37 40716 [Note] WSREP: cert trx map usage at exit 6
2014-10-09 10:24:37 40716 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:24:37 40716 [Note] WSREP: avg deps dist 1
2014-10-09 10:24:37 40716 [Note] WSREP: avg cert interval 0
2014-10-09 10:24:37 40716 [Note] WSREP: cert index size 6
2014-10-09 10:24:37 40716 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:37 40716 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:24:37 40716 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 2, in use: 0, in pool: 2
2014-10-09 10:24:37 40716 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 4, in use: 0, in pool: 4
2014-10-09 10:24:37 40716 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 6)
2014-10-09 10:24:37 40716 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:24:37 40716 [Note] Binlog end
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'partition'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_METRICS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_CMPMEM'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_CMP'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_LOCKS'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'INNODB_TRX'
2014-10-09 10:24:37 40716 [Note] Shutting down plugin 'InnoDB'
2014-10-09 10:24:37 40716 [Note] InnoDB: FTS optimize thread exiting.
2014-10-09 10:24:37 40716 [Note] InnoDB: Starting shutdown...
2014-10-09 10:24:38 40716 [Note] InnoDB: Shutdown completed; log sequence number 1641728
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'BLACKHOLE'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'ARCHIVE'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'CSV'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'MEMORY'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'MyISAM'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'MRG_MYISAM'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'sha256_password'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'mysql_old_password'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'mysql_native_password'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'wsrep'
2014-10-09 10:24:38 40716 [Note] Shutting down plugin 'binlog'
2014-10-09 10:24:38 40716 [Note] /home/philips/git/codership-mysql/sql/mysqld: Shutdown complete

CURRENT_TEST: galera.galera_binlog_event_max_size_min
2014-10-09 10:24:44 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2014-10-09 10:24:44 0 [Warning] WSREP: option --wsrep-casual-reads is deprecated
2014-10-09 10:24:44 40902 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-10-09 10:24:44 40902 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:24:44 40902 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:24:44 40902 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:24:44 40902 [Warning] WSREP: Could not open saved state file for reading: /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data//grastate.dat
2014-10-09 10:24:44 40902 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:44 40902 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; 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/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = f
2014-10-09 10:24:44 40902 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:44 40902 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2014-10-09 10:24:44 40902 [Note] WSREP: wsrep_sst_grab()
2014-10-09 10:24:44 40902 [Note] WSREP: Start replication
2014-10-09 10:24:44 40902 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:44 40902 [Note] WSREP: protonet asio version 0
2014-10-09 10:24:44 40902 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:24:44 40902 [Note] WSREP: backend: asio
2014-10-09 10:24:44 40902 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:24:44 40902 [Note] WSREP: restore pc from disk failed
2014-10-09 10:24:44 40902 [Note] WSREP: GMCast version 0
2014-10-09 10:24:44 40902 [Note] WSREP: (57a82658, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:24:44 40902 [Note] WSREP: (57a82658, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:24:44 40902 [Note] WSREP: EVS version 0
2014-10-09 10:24:44 40902 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:24:44 40902 [Note] WSREP: (57a82658, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:24:45 40902 [Note] WSREP: declaring 576dd3a8 at tcp://127.0.0.1:13001 stable
2014-10-09 10:24:45 40902 [Note] WSREP: Node 576dd3a8 state prim
2014-10-09 10:24:45 40902 [Note] WSREP: save pc into disk
2014-10-09 10:24:45 40902 [Note] WSREP: gcomm: connected
2014-10-09 10:24:45 40902 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:24:45 40902 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:24:45 40902 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:24:45 40902 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:24:45 40902 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:24:45 40902 [Note] WSREP: STATE EXCHANGE: sent state msg: 57f5a0e2-4f85-11e4-9bb5-662ab61ffa7f
2014-10-09 10:24:45 40902 [Note] WSREP: STATE EXCHANGE: got state msg: 57f5a0e2-4f85-11e4-9bb5-662ab61ffa7f from 0 (localhost.localdomain)
2014-10-09 10:24:45 40902 [Note] WSREP: STATE EXCHANGE: got state msg: 57f5a0e2-4f85-11e4-9bb5-662ab61ffa7f from 1 (localhost.localdomain)
2014-10-09 10:24:45 40902 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 1,
	members    = 1/2 (joined/total),
	act_id     = 0,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 576e385c-4f85-11e4-b846-820f7fbfd8f1
2014-10-09 10:24:45 40902 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:24:45 40902 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2014-10-09 10:24:45 40902 [Note] WSREP: Waiting for SST to complete.
2014-10-09 10:24:45 40902 [Note] WSREP: State transfer required: 
	Group state: 576e385c-4f85-11e4-b846-820f7fbfd8f1:0
	Local state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:45 40902 [Note] WSREP: New cluster view: global state: 576e385c-4f85-11e4-b846-820f7fbfd8f1:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:24:45 40902 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:24:45 40902 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1' --auth '' --datadir '/home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/' --defaults-file '/home/philips/git/codership-mysql/mysql-test/var/my.cnf' --parent '40902'  '' '
/home/philips/git/codership-mysql/scripts/wsrep_sst_common: line 98: my_print_defaults: command not found
/home/philips/git/codership-mysql/scripts/wsrep_sst_rsync: line 94: /home/philips/git/codership-mysql/scripts/my_print_defaults: No such file or directory
2014-10-09 10:24:45 40902 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:4444/rsync_sst
2014-10-09 10:24:45 40902 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:24:45 40902 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:24:45 40902 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:45 40902 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2014-10-09 10:24:45 40902 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:45 40902 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (576e385c-4f85-11e4-b846-820f7fbfd8f1): 1 (Operation not permitted)
	 at galera/src/replicator_str.cpp:prepare_for_IST():456. IST will be unavailable.
2014-10-09 10:24:45 40902 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:24:45 40902 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2014-10-09 10:24:45 40902 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:24:47 40902 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:24:47 40902 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:24:47 40902 [Note] WSREP: (57a82658, 'tcp://0.0.0.0:13004') turning message relay requesting off
WSREP_SST: [INFO] Joiner cleanup. (20141009 10:24:47.964)
WSREP_SST: [INFO] Joiner cleanup done. (20141009 10:24:48.552)
2014-10-09 10:24:48 40902 [Note] WSREP: SST complete, seqno: 0
2014-10-09 10:24:48 40902 [Note] Plugin 'FEDERATED' is disabled.
2014-10-09 10:24:48 7f68873ab780 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2014-10-09 10:24:48 40902 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-10-09 10:24:48 40902 [Note] InnoDB: The InnoDB memory heap is disabled
2014-10-09 10:24:48 40902 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-10-09 10:24:48 40902 [Note] InnoDB: Memory barrier is not used
2014-10-09 10:24:48 40902 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-10-09 10:24:48 40902 [Note] InnoDB: Using Linux native AIO
2014-10-09 10:24:48 40902 [Note] InnoDB: Using CPU crc32 instructions
2014-10-09 10:24:48 40902 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2014-10-09 10:24:48 40902 [Note] InnoDB: Completed initialization of buffer pool
2014-10-09 10:24:48 40902 [Note] InnoDB: Highest supported file format is Barracuda.
2014-10-09 10:24:48 40902 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1625977
2014-10-09 10:24:48 40902 [Note] InnoDB: Database was not shutdown normally!
2014-10-09 10:24:48 40902 [Note] InnoDB: Starting crash recovery.
2014-10-09 10:24:48 40902 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-10-09 10:24:48 40902 [Note] InnoDB: Restoring possible half-written data pages 
2014-10-09 10:24:48 40902 [Note] InnoDB: from the doublewrite buffer...
InnoDB: Doing recovery: scanned up to log sequence number 1626167
2014-10-09 10:24:48 40902 [Note] InnoDB: 128 rollback segment(s) are active.
2014-10-09 10:24:48 40902 [Note] InnoDB: Waiting for purge to start
2014-10-09 10:24:48 40902 [Note] InnoDB: 5.6.21 started; log sequence number 1626167
2014-10-09 10:24:48 40902 [Warning] /home/philips/git/codership-mysql/sql/mysqld: unknown variable 'loose-debug-sync-timeout=600'
2014-10-09 10:24:48 40902 [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: 5a07bb9c-4f85-11e4-89d1-000c29102922.
2014-10-09 10:24:48 40902 [Note] Server hostname (bind-address): '*'; port: 13003
2014-10-09 10:24:48 40902 [Note] IPv6 is available.
2014-10-09 10:24:48 40902 [Note]   - '::' resolves to '::';
2014-10-09 10:24:48 40902 [Note] Server socket created on IP: '::'.
2014-10-09 10:24:48 40902 [Note] Event Scheduler: Loaded 0 events
2014-10-09 10:24:48 40902 [Note] WSREP: Signalling provider to continue.
2014-10-09 10:24:48 40902 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:24:48 40902 [Note] WSREP: SST received: 576e385c-4f85-11e4-b846-820f7fbfd8f1:0
2014-10-09 10:24:48 40902 [Note] /home/philips/git/codership-mysql/sql/mysqld: ready for connections.
Version: '5.6.21-log'  socket: '/home/philips/git/codership-mysql/mysql-test/var/tmp/mysqld.2.sock'  port: 13003  , wsrep_25.6.r4149
2014-10-09 10:24:48 40902 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:24:48 40902 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2014-10-09 10:24:48 40902 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:24:48 40902 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2014-10-09 10:24:48 40902 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:24:48 40902 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:24:49 40902 [Note] /home/philips/git/codership-mysql/sql/mysqld: Normal shutdown

2014-10-09 10:24:49 40902 [Note] WSREP: Stop replication
2014-10-09 10:24:49 40902 [Note] WSREP: Closing send monitor...
2014-10-09 10:24:49 40902 [Note] WSREP: Closed send monitor.
2014-10-09 10:24:49 40902 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:24:49 40902 [Note] WSREP: gcomm: joining thread
2014-10-09 10:24:49 40902 [Note] WSREP: gcomm: closing backend
2014-10-09 10:24:50 40902 [Note] WSREP: forgetting 576dd3a8 (tcp://127.0.0.1:13001)
2014-10-09 10:24:50 40902 [Note] WSREP: Node 57a82658 state prim
2014-10-09 10:24:50 40902 [Note] WSREP: save pc into disk
2014-10-09 10:24:50 40902 [Note] WSREP: forgetting 576dd3a8 (tcp://127.0.0.1:13001)
2014-10-09 10:24:50 40902 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:24:50 40902 [Note] WSREP: gcomm: closed
2014-10-09 10:24:50 40902 [Warning] WSREP: 0x2c27980 down context(s) not set
2014-10-09 10:24:50 40902 [Warning] WSREP: Failed to send state UUID: -107 (Transport endpoint is not connected)
2014-10-09 10:24:50 40902 [Note] WSREP: Received self-leave message.
2014-10-09 10:24:50 40902 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:24:50 40902 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:24:50 40902 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 5)
2014-10-09 10:24:50 40902 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:24:50 40902 [Note] WSREP: New cluster view: global state: 576e385c-4f85-11e4-b846-820f7fbfd8f1:5, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:24:50 40902 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:24:50 40902 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:24:50 40902 [Note] WSREP: recv_thread() joined.
2014-10-09 10:24:50 40902 [Note] WSREP: Closing replication queue.
2014-10-09 10:24:50 40902 [Note] WSREP: Closing slave action queue.
2014-10-09 10:24:52 40902 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:24:52 40902 [Note] Giving 0 client threads a chance to die gracefully
2014-10-09 10:24:52 40902 [Note] Event Scheduler: Purging the queue. 0 events
2014-10-09 10:24:52 40902 [Note] Shutting down slave threads
2014-10-09 10:24:52 40902 [Note] Forcefully disconnecting 0 remaining clients
2014-10-09 10:24:52 40902 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:24:52 40902 [Note] WSREP: mon: entered 5 oooe fraction 0 oool fraction 0
2014-10-09 10:24:52 40902 [Note] WSREP: mon: entered 5 oooe fraction 0 oool fraction 0
2014-10-09 10:24:52 40902 [Note] WSREP: mon: entered 9 oooe fraction 0 oool fraction 0.111111
2014-10-09 10:24:52 40902 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:24:52 40902 [Note] WSREP: cert trx map usage at exit 5
2014-10-09 10:24:52 40902 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:24:52 40902 [Note] WSREP: avg deps dist 1
2014-10-09 10:24:52 40902 [Note] WSREP: avg cert interval 0
2014-10-09 10:24:52 40902 [Note] WSREP: cert index size 5
2014-10-09 10:24:52 40902 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:52 40902 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:24:52 40902 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 2, in use: 0, in pool: 2
2014-10-09 10:24:52 40902 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 3, in use: 0, in pool: 3
2014-10-09 10:24:52 40902 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 5)
2014-10-09 10:24:52 40902 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:24:52 40902 [Note] Binlog end
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'partition'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_METRICS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_CMPMEM'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_CMP'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_LOCKS'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'INNODB_TRX'
2014-10-09 10:24:52 40902 [Note] Shutting down plugin 'InnoDB'
2014-10-09 10:24:52 40902 [Note] InnoDB: FTS optimize thread exiting.
2014-10-09 10:24:52 40902 [Note] InnoDB: Starting shutdown...
CURRENT_TEST: galera.galera_binlog_event_max_size_max
2014-10-09 10:24:53 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2014-10-09 10:24:53 0 [Warning] WSREP: option --wsrep-casual-reads is deprecated
2014-10-09 10:24:54 41093 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-10-09 10:24:54 41093 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:24:54 41093 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:24:54 41093 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:24:54 41093 [Warning] WSREP: Could not open saved state file for reading: /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data//grastate.dat
2014-10-09 10:24:54 41093 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:54 41093 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; 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/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = f
2014-10-09 10:24:54 41093 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:54 41093 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2014-10-09 10:24:54 41093 [Note] WSREP: wsrep_sst_grab()
2014-10-09 10:24:54 41093 [Note] WSREP: Start replication
2014-10-09 10:24:54 41093 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:54 41093 [Note] WSREP: protonet asio version 0
2014-10-09 10:24:54 41093 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:24:54 41093 [Note] WSREP: backend: asio
2014-10-09 10:24:54 41093 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:24:54 41093 [Note] WSREP: restore pc from disk failed
2014-10-09 10:24:54 41093 [Note] WSREP: GMCast version 0
2014-10-09 10:24:54 41093 [Note] WSREP: (5d29d5fa, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:24:54 41093 [Note] WSREP: (5d29d5fa, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:24:54 41093 [Note] WSREP: EVS version 0
2014-10-09 10:24:54 41093 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:24:54 41093 [Note] WSREP: (5d29d5fa, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:24:54 41093 [Note] WSREP: declaring 5ced1434 at tcp://127.0.0.1:13001 stable
2014-10-09 10:24:54 41093 [Note] WSREP: Node 5ced1434 state prim
2014-10-09 10:24:54 41093 [Note] WSREP: save pc into disk
2014-10-09 10:24:55 41093 [Note] WSREP: gcomm: connected
2014-10-09 10:24:55 41093 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:24:55 41093 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:24:55 41093 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:24:55 41093 [Note] WSREP: Waiting for SST to complete.
2014-10-09 10:24:55 41093 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:24:55 41093 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:24:55 41093 [Note] WSREP: STATE EXCHANGE: sent state msg: 5d776375-4f85-11e4-a2e7-4e14c7a11c22
2014-10-09 10:24:55 41093 [Note] WSREP: STATE EXCHANGE: got state msg: 5d776375-4f85-11e4-a2e7-4e14c7a11c22 from 0 (localhost.localdomain)
2014-10-09 10:24:55 41093 [Note] WSREP: STATE EXCHANGE: got state msg: 5d776375-4f85-11e4-a2e7-4e14c7a11c22 from 1 (localhost.localdomain)
2014-10-09 10:24:55 41093 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 1,
	members    = 1/2 (joined/total),
	act_id     = 0,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 5ceffd6f-4f85-11e4-a2d9-df72195eceef
2014-10-09 10:24:55 41093 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:24:55 41093 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2014-10-09 10:24:55 41093 [Note] WSREP: State transfer required: 
	Group state: 5ceffd6f-4f85-11e4-a2d9-df72195eceef:0
	Local state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:24:55 41093 [Note] WSREP: New cluster view: global state: 5ceffd6f-4f85-11e4-a2d9-df72195eceef:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:24:55 41093 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:24:55 41093 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1' --auth '' --datadir '/home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/' --defaults-file '/home/philips/git/codership-mysql/mysql-test/var/my.cnf' --parent '41093'  '' '
/home/philips/git/codership-mysql/scripts/wsrep_sst_common: line 98: my_print_defaults: command not found
/home/philips/git/codership-mysql/scripts/wsrep_sst_rsync: line 94: /home/philips/git/codership-mysql/scripts/my_print_defaults: No such file or directory
2014-10-09 10:24:55 41093 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:4444/rsync_sst
2014-10-09 10:24:55 41093 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:24:55 41093 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:24:55 41093 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:55 41093 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2014-10-09 10:24:55 41093 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:24:55 41093 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (5ceffd6f-4f85-11e4-a2d9-df72195eceef): 1 (Operation not permitted)
	 at galera/src/replicator_str.cpp:prepare_for_IST():456. IST will be unavailable.
2014-10-09 10:24:55 41093 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:24:55 41093 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2014-10-09 10:24:55 41093 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:24:56 41093 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:24:56 41093 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:24:57 41093 [Note] WSREP: (5d29d5fa, 'tcp://0.0.0.0:13004') turning message relay requesting off
WSREP_SST: [INFO] Joiner cleanup. (20141009 10:24:57.219)
WSREP_SST: [INFO] Joiner cleanup done. (20141009 10:24:57.743)
2014-10-09 10:24:57 41093 [Note] WSREP: SST complete, seqno: 0
2014-10-09 10:24:57 41093 [Note] Plugin 'FEDERATED' is disabled.
2014-10-09 10:24:57 7fa8e1323780 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2014-10-09 10:24:57 41093 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-10-09 10:24:57 41093 [Note] InnoDB: The InnoDB memory heap is disabled
2014-10-09 10:24:57 41093 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-10-09 10:24:57 41093 [Note] InnoDB: Memory barrier is not used
2014-10-09 10:24:57 41093 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-10-09 10:24:57 41093 [Note] InnoDB: Using Linux native AIO
2014-10-09 10:24:57 41093 [Note] InnoDB: Using CPU crc32 instructions
2014-10-09 10:24:57 41093 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2014-10-09 10:24:57 41093 [Note] InnoDB: Completed initialization of buffer pool
2014-10-09 10:24:57 41093 [Note] InnoDB: Highest supported file format is Barracuda.
2014-10-09 10:24:57 41093 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1625977
2014-10-09 10:24:57 41093 [Note] InnoDB: Database was not shutdown normally!
2014-10-09 10:24:57 41093 [Note] InnoDB: Starting crash recovery.
2014-10-09 10:24:57 41093 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-10-09 10:24:57 41093 [Note] InnoDB: Restoring possible half-written data pages 
2014-10-09 10:24:57 41093 [Note] InnoDB: from the doublewrite buffer...
InnoDB: Doing recovery: scanned up to log sequence number 1626167
2014-10-09 10:24:57 41093 [Note] InnoDB: 128 rollback segment(s) are active.
2014-10-09 10:24:57 41093 [Note] InnoDB: Waiting for purge to start
2014-10-09 10:24:57 41093 [Note] InnoDB: 5.6.21 started; log sequence number 1626167
2014-10-09 10:24:57 41093 [Warning] /home/philips/git/codership-mysql/sql/mysqld: unknown variable 'loose-debug-sync-timeout=600'
2014-10-09 10:24:57 41093 [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: 5f85d929-4f85-11e4-89d3-000c29102922.
2014-10-09 10:24:57 41093 [Note] Server hostname (bind-address): '*'; port: 13003
2014-10-09 10:24:57 41093 [Note] IPv6 is available.
2014-10-09 10:24:57 41093 [Note]   - '::' resolves to '::';
2014-10-09 10:24:57 41093 [Note] Server socket created on IP: '::'.
2014-10-09 10:24:58 41093 [Note] Event Scheduler: Loaded 0 events
2014-10-09 10:24:58 41093 [Note] WSREP: Signalling provider to continue.
2014-10-09 10:24:58 41093 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:24:58 41093 [Note] WSREP: SST received: 5ceffd6f-4f85-11e4-a2d9-df72195eceef:0
2014-10-09 10:24:58 41093 [Note] /home/philips/git/codership-mysql/sql/mysqld: ready for connections.
Version: '5.6.21-log'  socket: '/home/philips/git/codership-mysql/mysql-test/var/tmp/mysqld.2.sock'  port: 13003  , wsrep_25.6.r4149
2014-10-09 10:24:58 41093 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:24:58 41093 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2014-10-09 10:24:58 41093 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:24:58 41093 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2014-10-09 10:24:58 41093 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:24:58 41093 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:00 41093 [Note] /home/philips/git/codership-mysql/sql/mysqld: Normal shutdown

2014-10-09 10:25:00 41093 [Note] WSREP: Stop replication
2014-10-09 10:25:00 41093 [Note] WSREP: Closing send monitor...
2014-10-09 10:25:00 41093 [Note] WSREP: Closed send monitor.
2014-10-09 10:25:00 41093 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:25:00 41093 [Note] WSREP: gcomm: joining thread
2014-10-09 10:25:00 41093 [Note] WSREP: gcomm: closing backend
2014-10-09 10:25:01 41093 [Note] WSREP: forgetting 5ced1434 (tcp://127.0.0.1:13001)
2014-10-09 10:25:01 41093 [Note] WSREP: Node 5d29d5fa state prim
2014-10-09 10:25:01 41093 [Note] WSREP: save pc into disk
2014-10-09 10:25:01 41093 [Note] WSREP: forgetting 5ced1434 (tcp://127.0.0.1:13001)
2014-10-09 10:25:01 41093 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:25:01 41093 [Note] WSREP: gcomm: closed
2014-10-09 10:25:01 41093 [Warning] WSREP: 0x2f4b980 down context(s) not set
2014-10-09 10:25:01 41093 [Warning] WSREP: Failed to send state UUID: -107 (Transport endpoint is not connected)
2014-10-09 10:25:01 41093 [Note] WSREP: Received self-leave message.
2014-10-09 10:25:01 41093 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:25:01 41093 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:25:01 41093 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 8)
2014-10-09 10:25:01 41093 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:25:01 41093 [Note] WSREP: New cluster view: global state: 5ceffd6f-4f85-11e4-a2d9-df72195eceef:8, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:25:01 41093 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:01 41093 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:25:01 41093 [Note] WSREP: recv_thread() joined.
2014-10-09 10:25:01 41093 [Note] WSREP: Closing replication queue.
2014-10-09 10:25:01 41093 [Note] WSREP: Closing slave action queue.
2014-10-09 10:25:03 41093 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:25:03 41093 [Note] Giving 0 client threads a chance to die gracefully
2014-10-09 10:25:03 41093 [Note] Event Scheduler: Purging the queue. 0 events
2014-10-09 10:25:03 41093 [Note] Shutting down slave threads
2014-10-09 10:25:03 41093 [Note] Forcefully disconnecting 0 remaining clients
2014-10-09 10:25:03 41093 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:25:03 41093 [Note] WSREP: mon: entered 8 oooe fraction 0 oool fraction 0
2014-10-09 10:25:03 41093 [Note] WSREP: mon: entered 8 oooe fraction 0 oool fraction 0
2014-10-09 10:25:03 41093 [Note] WSREP: mon: entered 12 oooe fraction 0 oool fraction 0.0833333
2014-10-09 10:25:03 41093 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:25:03 41093 [Note] WSREP: cert trx map usage at exit 8
2014-10-09 10:25:03 41093 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:25:03 41093 [Note] WSREP: avg deps dist 1
2014-10-09 10:25:03 41093 [Note] WSREP: avg cert interval 0
2014-10-09 10:25:03 41093 [Note] WSREP: cert index size 16
2014-10-09 10:25:03 41093 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:03 41093 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:25:03 41093 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 3, in use: 0, in pool: 3
2014-10-09 10:25:03 41093 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 5, in use: 0, in pool: 5
2014-10-09 10:25:03 41093 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 8)
2014-10-09 10:25:03 41093 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:25:03 41093 [Note] Binlog end
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'partition'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_METRICS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_CMPMEM'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_CMP'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_LOCKS'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'INNODB_TRX'
2014-10-09 10:25:03 41093 [Note] Shutting down plugin 'InnoDB'
2014-10-09 10:25:03 41093 [Note] InnoDB: FTS optimize thread exiting.
2014-10-09 10:25:03 41093 [Note] InnoDB: Starting shutdown...
2014-10-09 10:25:04 41093 [Note] InnoDB: Shutdown completed; log sequence number 12620683
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'BLACKHOLE'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'ARCHIVE'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'CSV'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'MEMORY'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'MyISAM'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'MRG_MYISAM'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'sha256_password'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'mysql_old_password'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'mysql_native_password'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'wsrep'
2014-10-09 10:25:04 41093 [Note] Shutting down plugin 'binlog'
2014-10-09 10:25:04 41093 [Note] /home/philips/git/codership-mysql/sql/mysqld: Shutdown complete

CURRENT_TEST: galera.galera_v1_row_events
2014-10-09 10:25:11 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2014-10-09 10:25:11 0 [Warning] WSREP: option --wsrep-casual-reads is deprecated
2014-10-09 10:25:11 41286 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-10-09 10:25:11 41286 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:25:11 41286 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:25:11 41286 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:25:11 41286 [Warning] WSREP: Could not open saved state file for reading: /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data//grastate.dat
2014-10-09 10:25:11 41286 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:11 41286 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; 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/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = f
2014-10-09 10:25:11 41286 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:11 41286 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2014-10-09 10:25:11 41286 [Note] WSREP: wsrep_sst_grab()
2014-10-09 10:25:11 41286 [Note] WSREP: Start replication
2014-10-09 10:25:11 41286 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:11 41286 [Note] WSREP: protonet asio version 0
2014-10-09 10:25:11 41286 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:25:11 41286 [Note] WSREP: backend: asio
2014-10-09 10:25:11 41286 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:25:11 41286 [Note] WSREP: restore pc from disk failed
2014-10-09 10:25:11 41286 [Note] WSREP: GMCast version 0
2014-10-09 10:25:11 41286 [Note] WSREP: (674d59ed, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:25:11 41286 [Note] WSREP: (674d59ed, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:25:11 41286 [Note] WSREP: EVS version 0
2014-10-09 10:25:11 41286 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:25:11 41286 [Note] WSREP: (674d59ed, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:25:11 41286 [Note] WSREP: declaring 6711ae9d at tcp://127.0.0.1:13001 stable
2014-10-09 10:25:11 41286 [Note] WSREP: Node 6711ae9d state prim
2014-10-09 10:25:11 41286 [Note] WSREP: save pc into disk
2014-10-09 10:25:12 41286 [Note] WSREP: gcomm: connected
2014-10-09 10:25:12 41286 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:25:12 41286 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:25:12 41286 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:25:12 41286 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:25:12 41286 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:25:12 41286 [Note] WSREP: Waiting for SST to complete.
2014-10-09 10:25:12 41286 [Note] WSREP: STATE EXCHANGE: sent state msg: 679ae29f-4f85-11e4-ad89-0e8562468b20
2014-10-09 10:25:12 41286 [Note] WSREP: STATE EXCHANGE: got state msg: 679ae29f-4f85-11e4-ad89-0e8562468b20 from 0 (localhost.localdomain)
2014-10-09 10:25:12 41286 [Note] WSREP: STATE EXCHANGE: got state msg: 679ae29f-4f85-11e4-ad89-0e8562468b20 from 1 (localhost.localdomain)
2014-10-09 10:25:12 41286 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 1,
	members    = 1/2 (joined/total),
	act_id     = 0,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 67153499-4f85-11e4-8cbe-bef3792cf2f8
2014-10-09 10:25:12 41286 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:25:12 41286 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2014-10-09 10:25:12 41286 [Note] WSREP: State transfer required: 
	Group state: 67153499-4f85-11e4-8cbe-bef3792cf2f8:0
	Local state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:12 41286 [Note] WSREP: New cluster view: global state: 67153499-4f85-11e4-8cbe-bef3792cf2f8:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:25:12 41286 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:25:12 41286 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1' --auth '' --datadir '/home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/' --defaults-file '/home/philips/git/codership-mysql/mysql-test/var/my.cnf' --parent '41286'  '' '
/home/philips/git/codership-mysql/scripts/wsrep_sst_common: line 98: my_print_defaults: command not found
/home/philips/git/codership-mysql/scripts/wsrep_sst_rsync: line 94: /home/philips/git/codership-mysql/scripts/my_print_defaults: No such file or directory
2014-10-09 10:25:12 41286 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:4444/rsync_sst
2014-10-09 10:25:12 41286 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:12 41286 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:25:12 41286 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:12 41286 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2014-10-09 10:25:12 41286 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:12 41286 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (67153499-4f85-11e4-8cbe-bef3792cf2f8): 1 (Operation not permitted)
	 at galera/src/replicator_str.cpp:prepare_for_IST():456. IST will be unavailable.
2014-10-09 10:25:12 41286 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:25:12 41286 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2014-10-09 10:25:12 41286 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:25:13 41286 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:25:13 41286 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:25:14 41286 [Note] WSREP: (674d59ed, 'tcp://0.0.0.0:13004') turning message relay requesting off
WSREP_SST: [INFO] Joiner cleanup. (20141009 10:25:14.211)
WSREP_SST: [INFO] Joiner cleanup done. (20141009 10:25:14.734)
2014-10-09 10:25:14 41286 [Note] WSREP: SST complete, seqno: 0
2014-10-09 10:25:14 41286 [Note] Plugin 'FEDERATED' is disabled.
2014-10-09 10:25:14 7f1209bfa780 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2014-10-09 10:25:14 41286 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-10-09 10:25:14 41286 [Note] InnoDB: The InnoDB memory heap is disabled
2014-10-09 10:25:14 41286 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-10-09 10:25:14 41286 [Note] InnoDB: Memory barrier is not used
2014-10-09 10:25:14 41286 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-10-09 10:25:14 41286 [Note] InnoDB: Using Linux native AIO
2014-10-09 10:25:14 41286 [Note] InnoDB: Using CPU crc32 instructions
2014-10-09 10:25:14 41286 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2014-10-09 10:25:14 41286 [Note] InnoDB: Completed initialization of buffer pool
2014-10-09 10:25:14 41286 [Note] InnoDB: Highest supported file format is Barracuda.
2014-10-09 10:25:14 41286 [Note] InnoDB: The log sequence numbers 1625977 and 1625977 in ibdata files do not match the log sequence number 1626167 in the ib_logfiles!
2014-10-09 10:25:14 41286 [Note] InnoDB: Database was not shutdown normally!
2014-10-09 10:25:14 41286 [Note] InnoDB: Starting crash recovery.
2014-10-09 10:25:14 41286 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-10-09 10:25:14 41286 [Note] InnoDB: Restoring possible half-written data pages 
2014-10-09 10:25:14 41286 [Note] InnoDB: from the doublewrite buffer...
2014-10-09 10:25:14 41286 [Note] InnoDB: 128 rollback segment(s) are active.
2014-10-09 10:25:14 41286 [Note] InnoDB: Waiting for purge to start
2014-10-09 10:25:14 41286 [Note] InnoDB: 5.6.21 started; log sequence number 1626167
2014-10-09 10:25:14 41286 [Warning] /home/philips/git/codership-mysql/sql/mysqld: unknown variable 'loose-debug-sync-timeout=600'
2014-10-09 10:25:14 41286 [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: 69a97700-4f85-11e4-89d8-000c29102922.
2014-10-09 10:25:15 41286 [Note] Server hostname (bind-address): '*'; port: 13003
2014-10-09 10:25:15 41286 [Note] IPv6 is available.
2014-10-09 10:25:15 41286 [Note]   - '::' resolves to '::';
2014-10-09 10:25:15 41286 [Note] Server socket created on IP: '::'.
2014-10-09 10:25:15 41286 [Note] Event Scheduler: Loaded 0 events
2014-10-09 10:25:15 41286 [Note] WSREP: Signalling provider to continue.
2014-10-09 10:25:15 41286 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:25:15 41286 [Note] WSREP: SST received: 67153499-4f85-11e4-8cbe-bef3792cf2f8:0
2014-10-09 10:25:15 41286 [Note] /home/philips/git/codership-mysql/sql/mysqld: ready for connections.
Version: '5.6.21-log'  socket: '/home/philips/git/codership-mysql/mysql-test/var/tmp/mysqld.2.sock'  port: 13003  , wsrep_25.6.r4149
2014-10-09 10:25:15 41286 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:25:15 41286 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2014-10-09 10:25:15 41286 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:25:15 41286 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2014-10-09 10:25:15 41286 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:25:15 41286 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:15 41286 [Note] /home/philips/git/codership-mysql/sql/mysqld: Normal shutdown

2014-10-09 10:25:15 41286 [Note] WSREP: Stop replication
2014-10-09 10:25:15 41286 [Note] WSREP: Closing send monitor...
2014-10-09 10:25:15 41286 [Note] WSREP: Closed send monitor.
2014-10-09 10:25:15 41286 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:25:15 41286 [Note] WSREP: gcomm: joining thread
2014-10-09 10:25:15 41286 [Note] WSREP: gcomm: closing backend
2014-10-09 10:25:16 41286 [Note] WSREP: forgetting 6711ae9d (tcp://127.0.0.1:13001)
2014-10-09 10:25:16 41286 [Note] WSREP: Node 674d59ed state prim
2014-10-09 10:25:16 41286 [Note] WSREP: save pc into disk
2014-10-09 10:25:16 41286 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:25:16 41286 [Note] WSREP: forgetting 6711ae9d (tcp://127.0.0.1:13001)
2014-10-09 10:25:16 41286 [Note] WSREP: gcomm: closed
2014-10-09 10:25:16 41286 [Warning] WSREP: 0x27a0980 down context(s) not set
2014-10-09 10:25:16 41286 [Warning] WSREP: Failed to send state UUID: -107 (Transport endpoint is not connected)
2014-10-09 10:25:16 41286 [Note] WSREP: Received self-leave message.
2014-10-09 10:25:16 41286 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:25:16 41286 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:25:16 41286 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 6)
2014-10-09 10:25:16 41286 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:25:16 41286 [Note] WSREP: New cluster view: global state: 67153499-4f85-11e4-8cbe-bef3792cf2f8:6, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:25:16 41286 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:16 41286 [Note] WSREP: recv_thread() joined.
2014-10-09 10:25:16 41286 [Note] WSREP: Closing replication queue.
2014-10-09 10:25:16 41286 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:25:16 41286 [Note] WSREP: Closing slave action queue.
2014-10-09 10:25:18 41286 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:25:18 41286 [Note] Giving 0 client threads a chance to die gracefully
2014-10-09 10:25:18 41286 [Note] Event Scheduler: Purging the queue. 0 events
2014-10-09 10:25:18 41286 [Note] Shutting down slave threads
2014-10-09 10:25:18 41286 [Note] Forcefully disconnecting 0 remaining clients
2014-10-09 10:25:18 41286 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:25:18 41286 [Note] WSREP: mon: entered 6 oooe fraction 0 oool fraction 0
2014-10-09 10:25:18 41286 [Note] WSREP: mon: entered 6 oooe fraction 0 oool fraction 0
2014-10-09 10:25:18 41286 [Note] WSREP: mon: entered 10 oooe fraction 0 oool fraction 0.1
2014-10-09 10:25:18 41286 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:25:18 41286 [Note] WSREP: cert trx map usage at exit 6
2014-10-09 10:25:18 41286 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:25:18 41286 [Note] WSREP: avg deps dist 1
2014-10-09 10:25:18 41286 [Note] WSREP: avg cert interval 0
2014-10-09 10:25:18 41286 [Note] WSREP: cert index size 6
2014-10-09 10:25:18 41286 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:18 41286 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:25:18 41286 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 2, in use: 0, in pool: 2
2014-10-09 10:25:18 41286 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 4, in use: 0, in pool: 4
2014-10-09 10:25:18 41286 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 6)
2014-10-09 10:25:18 41286 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:25:18 41286 [Note] Binlog end
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'partition'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_METRICS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_CMPMEM'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_CMP'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_LOCKS'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'INNODB_TRX'
2014-10-09 10:25:18 41286 [Note] Shutting down plugin 'InnoDB'
2014-10-09 10:25:18 41286 [Note] InnoDB: FTS optimize thread exiting.
2014-10-09 10:25:18 41286 [Note] InnoDB: Starting shutdown...
2014-10-09 10:25:19 41286 [Note] InnoDB: Shutdown completed; log sequence number 1641728
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'BLACKHOLE'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'ARCHIVE'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'CSV'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'MEMORY'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'MyISAM'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'MRG_MYISAM'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'sha256_password'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'mysql_old_password'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'mysql_native_password'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'wsrep'
2014-10-09 10:25:19 41286 [Note] Shutting down plugin 'binlog'
2014-10-09 10:25:19 41286 [Note] /home/philips/git/codership-mysql/sql/mysqld: Shutdown complete

CURRENT_TEST: galera.galera_query_cache
2014-10-09 10:25:25 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2014-10-09 10:25:25 0 [Warning] WSREP: option --wsrep-casual-reads is deprecated
2014-10-09 10:25:25 41477 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-10-09 10:25:25 41477 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:25:25 41477 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:25:25 41477 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:25:25 41477 [Warning] WSREP: Could not open saved state file for reading: /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data//grastate.dat
2014-10-09 10:25:25 41477 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:25 41477 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; 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/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = f
2014-10-09 10:25:25 41477 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:25 41477 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2014-10-09 10:25:25 41477 [Note] WSREP: wsrep_sst_grab()
2014-10-09 10:25:25 41477 [Note] WSREP: Start replication
2014-10-09 10:25:25 41477 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:25 41477 [Note] WSREP: protonet asio version 0
2014-10-09 10:25:25 41477 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:25:25 41477 [Note] WSREP: backend: asio
2014-10-09 10:25:25 41477 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:25:25 41477 [Note] WSREP: restore pc from disk failed
2014-10-09 10:25:25 41477 [Note] WSREP: GMCast version 0
2014-10-09 10:25:25 41477 [Note] WSREP: (701c7c26, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:25:25 41477 [Note] WSREP: (701c7c26, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:25:25 41477 [Note] WSREP: EVS version 0
2014-10-09 10:25:25 41477 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:25:25 41477 [Note] WSREP: (701c7c26, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:25:26 41477 [Note] WSREP: declaring 6fe11d20 at tcp://127.0.0.1:13001 stable
2014-10-09 10:25:26 41477 [Note] WSREP: Node 6fe11d20 state prim
2014-10-09 10:25:26 41477 [Note] WSREP: save pc into disk
2014-10-09 10:25:26 41477 [Note] WSREP: gcomm: connected
2014-10-09 10:25:26 41477 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:25:26 41477 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:25:26 41477 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:25:26 41477 [Note] WSREP: Waiting for SST to complete.
2014-10-09 10:25:26 41477 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:25:26 41477 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:25:26 41477 [Note] WSREP: STATE EXCHANGE: sent state msg: 706a148e-4f85-11e4-902e-a2d5351969d8
2014-10-09 10:25:26 41477 [Note] WSREP: STATE EXCHANGE: got state msg: 706a148e-4f85-11e4-902e-a2d5351969d8 from 0 (localhost.localdomain)
2014-10-09 10:25:26 41477 [Note] WSREP: STATE EXCHANGE: got state msg: 706a148e-4f85-11e4-902e-a2d5351969d8 from 1 (localhost.localdomain)
2014-10-09 10:25:26 41477 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 1,
	members    = 1/2 (joined/total),
	act_id     = 0,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 6fe3a3d0-4f85-11e4-a818-a7049dd77a81
2014-10-09 10:25:26 41477 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:25:26 41477 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2014-10-09 10:25:26 41477 [Note] WSREP: State transfer required: 
	Group state: 6fe3a3d0-4f85-11e4-a818-a7049dd77a81:0
	Local state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:26 41477 [Note] WSREP: New cluster view: global state: 6fe3a3d0-4f85-11e4-a818-a7049dd77a81:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:25:26 41477 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:25:26 41477 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1' --auth '' --datadir '/home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/' --defaults-file '/home/philips/git/codership-mysql/mysql-test/var/my.cnf' --parent '41477'  '' '
/home/philips/git/codership-mysql/scripts/wsrep_sst_common: line 98: my_print_defaults: command not found
/home/philips/git/codership-mysql/scripts/wsrep_sst_rsync: line 94: /home/philips/git/codership-mysql/scripts/my_print_defaults: No such file or directory
2014-10-09 10:25:26 41477 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:4444/rsync_sst
2014-10-09 10:25:26 41477 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:26 41477 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:25:26 41477 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:26 41477 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2014-10-09 10:25:26 41477 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:26 41477 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (6fe3a3d0-4f85-11e4-a818-a7049dd77a81): 1 (Operation not permitted)
	 at galera/src/replicator_str.cpp:prepare_for_IST():456. IST will be unavailable.
2014-10-09 10:25:26 41477 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:25:26 41477 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2014-10-09 10:25:26 41477 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:25:28 41477 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:25:28 41477 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:25:28 41477 [Note] WSREP: (701c7c26, 'tcp://0.0.0.0:13004') turning message relay requesting off
WSREP_SST: [INFO] Joiner cleanup. (20141009 10:25:28.967)
WSREP_SST: [INFO] Joiner cleanup done. (20141009 10:25:29.488)
2014-10-09 10:25:29 41477 [Note] WSREP: SST complete, seqno: 0
2014-10-09 10:25:29 41477 [Note] Plugin 'FEDERATED' is disabled.
2014-10-09 10:25:29 7f4b2f399780 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2014-10-09 10:25:29 41477 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-10-09 10:25:29 41477 [Note] InnoDB: The InnoDB memory heap is disabled
2014-10-09 10:25:29 41477 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-10-09 10:25:29 41477 [Note] InnoDB: Memory barrier is not used
2014-10-09 10:25:29 41477 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-10-09 10:25:29 41477 [Note] InnoDB: Using Linux native AIO
2014-10-09 10:25:29 41477 [Note] InnoDB: Using CPU crc32 instructions
2014-10-09 10:25:29 41477 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2014-10-09 10:25:29 41477 [Note] InnoDB: Completed initialization of buffer pool
2014-10-09 10:25:29 41477 [Note] InnoDB: Highest supported file format is Barracuda.
2014-10-09 10:25:29 41477 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1625977
2014-10-09 10:25:29 41477 [Note] InnoDB: Database was not shutdown normally!
2014-10-09 10:25:29 41477 [Note] InnoDB: Starting crash recovery.
2014-10-09 10:25:29 41477 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-10-09 10:25:29 41477 [Note] InnoDB: Restoring possible half-written data pages 
2014-10-09 10:25:29 41477 [Note] InnoDB: from the doublewrite buffer...
InnoDB: Doing recovery: scanned up to log sequence number 1626167
2014-10-09 10:25:29 41477 [Note] InnoDB: 128 rollback segment(s) are active.
2014-10-09 10:25:29 41477 [Note] InnoDB: Waiting for purge to start
2014-10-09 10:25:29 41477 [Note] InnoDB: 5.6.21 started; log sequence number 1626167
2014-10-09 10:25:29 41477 [Warning] /home/philips/git/codership-mysql/sql/mysqld: unknown variable 'loose-debug-sync-timeout=600'
2014-10-09 10:25:29 41477 [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: 7270c591-4f85-11e4-89dc-000c29102922.
2014-10-09 10:25:29 41477 [Note] Server hostname (bind-address): '*'; port: 13003
2014-10-09 10:25:29 41477 [Note] IPv6 is available.
2014-10-09 10:25:29 41477 [Note]   - '::' resolves to '::';
2014-10-09 10:25:29 41477 [Note] Server socket created on IP: '::'.
2014-10-09 10:25:29 41477 [Note] Event Scheduler: Loaded 0 events
2014-10-09 10:25:29 41477 [Note] WSREP: Signalling provider to continue.
2014-10-09 10:25:29 41477 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:25:29 41477 [Note] WSREP: SST received: 6fe3a3d0-4f85-11e4-a818-a7049dd77a81:0
2014-10-09 10:25:29 41477 [Note] /home/philips/git/codership-mysql/sql/mysqld: ready for connections.
Version: '5.6.21-log'  socket: '/home/philips/git/codership-mysql/mysql-test/var/tmp/mysqld.2.sock'  port: 13003  , wsrep_25.6.r4149
2014-10-09 10:25:29 41477 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:25:29 41477 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2014-10-09 10:25:29 41477 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:25:29 41477 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2014-10-09 10:25:29 41477 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:25:29 41477 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:30 41477 [Note] /home/philips/git/codership-mysql/sql/mysqld: Normal shutdown

2014-10-09 10:25:30 41477 [Note] WSREP: Stop replication
2014-10-09 10:25:30 41477 [Note] WSREP: Closing send monitor...
2014-10-09 10:25:30 41477 [Note] WSREP: Closed send monitor.
2014-10-09 10:25:30 41477 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:25:30 41477 [Note] WSREP: gcomm: joining thread
2014-10-09 10:25:30 41477 [Note] WSREP: gcomm: closing backend
2014-10-09 10:25:31 41477 [Note] WSREP: forgetting 6fe11d20 (tcp://127.0.0.1:13001)
2014-10-09 10:25:31 41477 [Note] WSREP: Node 701c7c26 state prim
2014-10-09 10:25:31 41477 [Note] WSREP: save pc into disk
2014-10-09 10:25:31 41477 [Note] WSREP: forgetting 6fe11d20 (tcp://127.0.0.1:13001)
2014-10-09 10:25:31 41477 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:25:31 41477 [Note] WSREP: gcomm: closed
2014-10-09 10:25:31 41477 [Warning] WSREP: 0x314e980 down context(s) not set
2014-10-09 10:25:31 41477 [Warning] WSREP: Failed to send state UUID: -107 (Transport endpoint is not connected)
2014-10-09 10:25:31 41477 [Note] WSREP: Received self-leave message.
2014-10-09 10:25:31 41477 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:25:31 41477 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:25:31 41477 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 7)
2014-10-09 10:25:31 41477 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:25:31 41477 [Note] WSREP: New cluster view: global state: 6fe3a3d0-4f85-11e4-a818-a7049dd77a81:7, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:25:31 41477 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:31 41477 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:25:31 41477 [Note] WSREP: recv_thread() joined.
2014-10-09 10:25:31 41477 [Note] WSREP: Closing replication queue.
2014-10-09 10:25:31 41477 [Note] WSREP: Closing slave action queue.
2014-10-09 10:25:33 41477 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:25:33 41477 [Note] Giving 0 client threads a chance to die gracefully
2014-10-09 10:25:33 41477 [Note] Event Scheduler: Purging the queue. 0 events
2014-10-09 10:25:33 41477 [Note] Shutting down slave threads
2014-10-09 10:25:33 41477 [Note] Forcefully disconnecting 0 remaining clients
2014-10-09 10:25:33 41477 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:25:33 41477 [Note] WSREP: mon: entered 3 oooe fraction 0 oool fraction 0
2014-10-09 10:25:33 41477 [Note] WSREP: mon: entered 3 oooe fraction 0 oool fraction 0
2014-10-09 10:25:33 41477 [Note] WSREP: mon: entered 11 oooe fraction 0 oool fraction 0.0909091
2014-10-09 10:25:33 41477 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:25:33 41477 [Note] WSREP: cert trx map usage at exit 7
2014-10-09 10:25:33 41477 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:25:33 41477 [Note] WSREP: avg deps dist 1
2014-10-09 10:25:33 41477 [Note] WSREP: avg cert interval 0
2014-10-09 10:25:33 41477 [Note] WSREP: cert index size 6
2014-10-09 10:25:33 41477 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:33 41477 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:25:33 41477 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 2, in use: 0, in pool: 2
2014-10-09 10:25:33 41477 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 5, in use: 0, in pool: 5
2014-10-09 10:25:33 41477 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 7)
2014-10-09 10:25:33 41477 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:25:33 41477 [Note] Binlog end
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'partition'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_METRICS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_CMPMEM'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_CMP'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_LOCKS'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'INNODB_TRX'
2014-10-09 10:25:33 41477 [Note] Shutting down plugin 'InnoDB'
2014-10-09 10:25:33 41477 [Note] InnoDB: FTS optimize thread exiting.
2014-10-09 10:25:33 41477 [Note] InnoDB: Starting shutdown...
2014-10-09 10:25:34 41477 [Note] InnoDB: Shutdown completed; log sequence number 1654614
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'BLACKHOLE'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'ARCHIVE'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'CSV'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'MEMORY'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'MyISAM'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'MRG_MYISAM'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'sha256_password'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'mysql_old_password'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'mysql_native_password'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'wsrep'
2014-10-09 10:25:34 41477 [Note] Shutting down plugin 'binlog'
2014-10-09 10:25:34 41477 [Note] /home/philips/git/codership-mysql/sql/mysqld: Shutdown complete

CURRENT_TEST: galera.galera_account_management
2014-10-09 10:25:41 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2014-10-09 10:25:41 0 [Warning] WSREP: option --wsrep-casual-reads is deprecated
2014-10-09 10:25:41 41668 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-10-09 10:25:41 41668 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:25:41 41668 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:25:41 41668 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:25:41 41668 [Warning] WSREP: Could not open saved state file for reading: /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data//grastate.dat
2014-10-09 10:25:41 41668 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:41 41668 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; 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/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = f
2014-10-09 10:25:41 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:41 41668 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2014-10-09 10:25:41 41668 [Note] WSREP: wsrep_sst_grab()
2014-10-09 10:25:41 41668 [Note] WSREP: Start replication
2014-10-09 10:25:41 41668 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:41 41668 [Note] WSREP: protonet asio version 0
2014-10-09 10:25:41 41668 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:25:41 41668 [Note] WSREP: backend: asio
2014-10-09 10:25:41 41668 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:25:41 41668 [Note] WSREP: restore pc from disk failed
2014-10-09 10:25:41 41668 [Note] WSREP: GMCast version 0
2014-10-09 10:25:41 41668 [Note] WSREP: (79357a71, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:25:41 41668 [Note] WSREP: (79357a71, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:25:41 41668 [Note] WSREP: EVS version 0
2014-10-09 10:25:41 41668 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:25:41 41668 [Note] WSREP: (79357a71, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:25:41 41668 [Note] WSREP: declaring 78f99996 at tcp://127.0.0.1:13001 stable
2014-10-09 10:25:41 41668 [Note] WSREP: Node 78f99996 state prim
2014-10-09 10:25:41 41668 [Note] WSREP: save pc into disk
2014-10-09 10:25:42 41668 [Note] WSREP: gcomm: connected
2014-10-09 10:25:42 41668 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:25:42 41668 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:25:42 41668 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:25:42 41668 [Note] WSREP: Waiting for SST to complete.
2014-10-09 10:25:42 41668 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:25:42 41668 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:25:42 41668 [Note] WSREP: STATE EXCHANGE: sent state msg: 79834ee2-4f85-11e4-9227-832d4d953fe4
2014-10-09 10:25:42 41668 [Note] WSREP: STATE EXCHANGE: got state msg: 79834ee2-4f85-11e4-9227-832d4d953fe4 from 0 (localhost.localdomain)
2014-10-09 10:25:42 41668 [Note] WSREP: STATE EXCHANGE: got state msg: 79834ee2-4f85-11e4-9227-832d4d953fe4 from 1 (localhost.localdomain)
2014-10-09 10:25:42 41668 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 1,
	members    = 1/2 (joined/total),
	act_id     = 0,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d
2014-10-09 10:25:42 41668 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:25:42 41668 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2014-10-09 10:25:42 41668 [Note] WSREP: State transfer required: 
	Group state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:0
	Local state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:25:42 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:25:42 41668 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:25:42 41668 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1' --auth '' --datadir '/home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/' --defaults-file '/home/philips/git/codership-mysql/mysql-test/var/my.cnf' --parent '41668'  '' '
/home/philips/git/codership-mysql/scripts/wsrep_sst_common: line 98: my_print_defaults: command not found
/home/philips/git/codership-mysql/scripts/wsrep_sst_rsync: line 94: /home/philips/git/codership-mysql/scripts/my_print_defaults: No such file or directory
2014-10-09 10:25:42 41668 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:4444/rsync_sst
2014-10-09 10:25:42 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:25:42 41668 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:25:42 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:42 41668 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2014-10-09 10:25:42 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:25:42 41668 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d): 1 (Operation not permitted)
	 at galera/src/replicator_str.cpp:prepare_for_IST():456. IST will be unavailable.
2014-10-09 10:25:42 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:25:42 41668 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2014-10-09 10:25:42 41668 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:25:43 41668 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:25:43 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:25:44 41668 [Note] WSREP: (79357a71, 'tcp://0.0.0.0:13004') turning message relay requesting off
WSREP_SST: [INFO] Joiner cleanup. (20141009 10:25:44.310)
WSREP_SST: [INFO] Joiner cleanup done. (20141009 10:25:44.848)
2014-10-09 10:25:44 41668 [Note] WSREP: SST complete, seqno: 0
2014-10-09 10:25:44 41668 [Note] Plugin 'FEDERATED' is disabled.
2014-10-09 10:25:44 7f5a05f5c780 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2014-10-09 10:25:44 41668 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-10-09 10:25:44 41668 [Note] InnoDB: The InnoDB memory heap is disabled
2014-10-09 10:25:44 41668 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-10-09 10:25:44 41668 [Note] InnoDB: Memory barrier is not used
2014-10-09 10:25:44 41668 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-10-09 10:25:44 41668 [Note] InnoDB: Using Linux native AIO
2014-10-09 10:25:44 41668 [Note] InnoDB: Using CPU crc32 instructions
2014-10-09 10:25:44 41668 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2014-10-09 10:25:44 41668 [Note] InnoDB: Completed initialization of buffer pool
2014-10-09 10:25:44 41668 [Note] InnoDB: Highest supported file format is Barracuda.
2014-10-09 10:25:44 41668 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1625977
2014-10-09 10:25:44 41668 [Note] InnoDB: Database was not shutdown normally!
2014-10-09 10:25:44 41668 [Note] InnoDB: Starting crash recovery.
2014-10-09 10:25:44 41668 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-10-09 10:25:44 41668 [Note] InnoDB: Restoring possible half-written data pages 
2014-10-09 10:25:44 41668 [Note] InnoDB: from the doublewrite buffer...
InnoDB: Doing recovery: scanned up to log sequence number 1626167
2014-10-09 10:25:45 41668 [Note] InnoDB: 128 rollback segment(s) are active.
2014-10-09 10:25:45 41668 [Note] InnoDB: Waiting for purge to start
2014-10-09 10:25:45 41668 [Note] InnoDB: 5.6.21 started; log sequence number 1626167
2014-10-09 10:25:45 41668 [Warning] /home/philips/git/codership-mysql/sql/mysqld: unknown variable 'loose-debug-sync-timeout=600'
2014-10-09 10:25:45 41668 [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: 7b999a8a-4f85-11e4-89e1-000c29102922.
2014-10-09 10:25:45 41668 [Note] Server hostname (bind-address): '*'; port: 13003
2014-10-09 10:25:45 41668 [Note] IPv6 is available.
2014-10-09 10:25:45 41668 [Note]   - '::' resolves to '::';
2014-10-09 10:25:45 41668 [Note] Server socket created on IP: '::'.
2014-10-09 10:25:45 41668 [Note] Event Scheduler: Loaded 0 events
2014-10-09 10:25:45 41668 [Note] WSREP: Signalling provider to continue.
2014-10-09 10:25:45 41668 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:25:45 41668 [Note] WSREP: SST received: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:0
2014-10-09 10:25:45 41668 [Note] /home/philips/git/codership-mysql/sql/mysqld: ready for connections.
Version: '5.6.21-log'  socket: '/home/philips/git/codership-mysql/mysql-test/var/tmp/mysqld.2.sock'  port: 13003  , wsrep_25.6.r4149
2014-10-09 10:25:45 41668 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:25:45 41668 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2014-10-09 10:25:45 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:25:45 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2014-10-09 10:25:45 41668 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:25:45 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
CURRENT_TEST: galera.galera_bf_abort
CURRENT_TEST: galera.galera_bf_abort_for_update
CURRENT_TEST: galera.galera_bf_abort_ftwrl
2014-10-09 10:25:46 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:23 (28)
2014-10-09 10:25:46 41668 [Note] WSREP: resuming provider at 28
2014-10-09 10:25:46 41668 [Note] WSREP: Provider resumed.
CURRENT_TEST: galera.galera_bf_abort_get_lock
CURRENT_TEST: galera.galera_bf_abort_lock_table
CURRENT_TEST: galera.galera_bf_abort_sleep
CURRENT_TEST: galera.galera_binlog_cache_size
CURRENT_TEST: galera.galera_binlog_row_image
CURRENT_TEST: galera.galera_binlog_rows_query_log_events
CURRENT_TEST: galera.galera_delete_limit
CURRENT_TEST: galera.galera_events
2014-10-09 10:25:49 41668 [Note] Event Scheduler: scheduler thread started with id 74
2014-10-09 10:25:49 41668 [Note] Event Scheduler: Last execution of test.event2. Dropping.
2014-10-09 10:25:49 41668 [Note] Event Scheduler: Dropping test.event2
2014-10-09 10:25:50 41668 [Note] Event Scheduler: Killing the scheduler thread, thread id 74
2014-10-09 10:25:50 41668 [Note] Event Scheduler: Waiting for the scheduler thread to reply
2014-10-09 10:25:50 41668 [Note] Event Scheduler: Stopped
CURRENT_TEST: galera.galera_fk_cascade_delete
CURRENT_TEST: galera.galera_fk_cascade_update
CURRENT_TEST: galera.galera_fk_conflict
CURRENT_TEST: galera.galera_fk_mismatch
CURRENT_TEST: galera.galera_fk_multicolumn
CURRENT_TEST: galera.galera_fk_multitable
CURRENT_TEST: galera.galera_fk_no_pk
CURRENT_TEST: galera.galera_fk_selfreferential
CURRENT_TEST: galera.galera_fk_setnull
CURRENT_TEST: galera.galera_ftwrl
2014-10-09 10:25:54 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:196 (203)
2014-10-09 10:25:56 41668 [Note] WSREP: resuming provider at 203
2014-10-09 10:25:56 41668 [Note] WSREP: Provider resumed.
CURRENT_TEST: galera.galera_fulltext
2014-10-09 10:25:57 7f59d5ffb700 InnoDB: FTS Optimize Removing table test/t1
2014-10-09 10:25:58 7f59d5ffb700 InnoDB: FTS Optimize Removing table test/t1
CURRENT_TEST: galera.galera_insert_ignore
CURRENT_TEST: galera.galera_insert_multi
CURRENT_TEST: galera.galera_lock_table
CURRENT_TEST: galera.galera_many_columns
CURRENT_TEST: galera.galera_many_indexes
CURRENT_TEST: galera.galera_many_rows
CURRENT_TEST: galera.galera_many_tables_nopk
CURRENT_TEST: galera.galera_many_tables_pk
CURRENT_TEST: galera.galera_nopk_bit
CURRENT_TEST: galera.galera_nopk_blob
CURRENT_TEST: galera.galera_nopk_large_varchar
CURRENT_TEST: galera.galera_nopk_unicode
CURRENT_TEST: galera.galera_pk_bigint_signed
CURRENT_TEST: galera.galera_pk_bigint_unsigned
CURRENT_TEST: galera.galera_read_only
2014-10-09 10:28:34 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6428 (6494)
2014-10-09 10:28:34 41668 [Note] WSREP: resuming provider at 6494
2014-10-09 10:28:34 41668 [Note] WSREP: Provider resumed.
CURRENT_TEST: galera.galera_rsu_add_pk
2014-10-09 10:28:36 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:28:36 41668 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 6436)
2014-10-09 10:28:36 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6436 (6504)
2014-10-09 10:28:37 41668 [Note] WSREP: resuming provider at 6504
2014-10-09 10:28:37 41668 [Note] WSREP: Provider resumed.
2014-10-09 10:28:37 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:28:37 41668 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 6437)
2014-10-09 10:28:37 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:28:37 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 6437)
2014-10-09 10:28:38 41668 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:28:41 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:28:44 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:28:44 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
CURRENT_TEST: galera.galera_rsu_drop_pk
2014-10-09 10:28:46 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:28:46 41668 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 6446)
2014-10-09 10:28:46 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6446 (6521)
2014-10-09 10:28:47 41668 [Note] WSREP: resuming provider at 6521
2014-10-09 10:28:47 41668 [Note] WSREP: Provider resumed.
2014-10-09 10:28:47 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:28:47 41668 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 6447)
2014-10-09 10:28:47 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:28:47 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 6447)
2014-10-09 10:28:48 41668 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:28:50 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:28:53 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:28:53 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
CURRENT_TEST: galera.galera_rsu_error
2014-10-09 10:28:54 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:28:54 41668 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 6458)
2014-10-09 10:28:54 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6458 (6540)
2014-10-09 10:28:54 41668 [Note] WSREP: resuming provider at 6540
2014-10-09 10:28:54 41668 [Note] WSREP: Provider resumed.
2014-10-09 10:28:54 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:28:54 41668 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 6458)
2014-10-09 10:28:54 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:28:54 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 6458)
2014-10-09 10:28:54 41668 [Note] WSREP: Synchronized with group, ready for connections
CURRENT_TEST: galera.galera_rsu_simple
2014-10-09 10:28:54 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:28:54 41668 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 6464)
2014-10-09 10:28:54 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6464 (6550)
2014-10-09 10:28:54 41668 [Note] WSREP: resuming provider at 6550
2014-10-09 10:28:54 41668 [Note] WSREP: Provider resumed.
2014-10-09 10:28:54 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:28:54 41668 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 6464)
2014-10-09 10:28:54 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:28:54 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 6464)
2014-10-09 10:28:54 41668 [Note] WSREP: Synchronized with group, ready for connections
CURRENT_TEST: galera.galera_sst_mysqldump
2014-10-09 10:28:55 41668 [Note] WSREP: Stop replication
2014-10-09 10:28:55 41668 [Note] WSREP: Closing send monitor...
2014-10-09 10:28:55 41668 [Note] WSREP: Closed send monitor.
2014-10-09 10:28:55 41668 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:28:55 41668 [Note] WSREP: gcomm: joining thread
2014-10-09 10:28:55 41668 [Note] WSREP: gcomm: closing backend
2014-10-09 10:28:56 41668 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:28:56 41668 [Note] WSREP: gcomm: closed
2014-10-09 10:28:56 41668 [Note] WSREP: Flow-control interval: [16, 16]
2014-10-09 10:28:56 41668 [Note] WSREP: Received NON-PRIMARY.
2014-10-09 10:28:56 41668 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 6472)
2014-10-09 10:28:56 41668 [Note] WSREP: Received self-leave message.
2014-10-09 10:28:56 41668 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:28:56 41668 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:28:56 41668 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 6472)
2014-10-09 10:28:56 41668 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:28:56 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6472, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
2014-10-09 10:28:56 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:28:56 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6472, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:28:56 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:28:56 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:28:56 41668 [Note] WSREP: recv_thread() joined.
2014-10-09 10:28:56 41668 [Note] WSREP: Closing replication queue.
2014-10-09 10:28:56 41668 [Note] WSREP: Closing slave action queue.
2014-10-09 10:28:58 41668 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:28:58 41668 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:28:58 41668 [Note] WSREP: mon: entered 6472 oooe fraction 0 oool fraction 0
2014-10-09 10:28:58 41668 [Note] WSREP: mon: entered 6472 oooe fraction 0.00154512 oool fraction 0
2014-10-09 10:28:58 41668 [Note] WSREP: mon: entered 6561 oooe fraction 0 oool fraction 0.000152416
2014-10-09 10:28:58 41668 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:28:58 41668 [Note] WSREP: cert trx map usage at exit 25
2014-10-09 10:28:58 41668 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:28:58 41668 [Note] WSREP: avg deps dist 11.5694
2014-10-09 10:28:58 41668 [Note] WSREP: avg cert interval 0.00200865
2014-10-09 10:28:58 41668 [Note] WSREP: cert index size 100012
2014-10-09 10:28:58 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:28:58 41668 [Note] WSREP: wsdb trx map usage 0 conn query map usage 1
2014-10-09 10:28:58 41668 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0.940958, misses: 127, in use: 0, in pool: 127
2014-10-09 10:28:58 41668 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0.965652, misses: 149, in use: 0, in pool: 149
2014-10-09 10:28:58 41668 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 6472)
2014-10-09 10:28:58 41668 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:28:58 41668 [Note] WSREP: Initial position: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6472
2014-10-09 10:28:58 41668 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:28:58 41668 [Note] WSREP: wsrep_load(): loading provider library 'none'
2014-10-09 10:29:01 41668 [Note] WSREP: Stop replication
2014-10-09 10:29:03 41668 [Note] WSREP: Initial position: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6472
2014-10-09 10:29:03 41668 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:29:03 41668 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:29:03 41668 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:29:03 41668 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:29:03 41668 [Note] WSREP: Found saved state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6472
2014-10-09 10:29:03 41668 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; cert.log_conflicts = no; debug = no; evs.causal_keepalive_period = PT1S; evs.debug_log_mask = 0x1; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.info_log_mask = 0; evs.install_timeout = PT7.5S; evs.join_retrans_period = PT1S; evs.keepalive_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.use_aggregate = true; evs.user_send_window = 2; evs.version = 0; evs.view_forget_timeout = P1D; gcache.dir = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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_sof
2014-10-09 10:29:03 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:29:03 41668 [Note] WSREP: Assign initial position for certification: 6472, protocol version: -1
2014-10-09 10:29:03 41668 [Note] WSREP: Stop replication
2014-10-09 10:29:05 41668 [Note] WSREP: Start replication
2014-10-09 10:29:05 41668 [Note] WSREP: Setting initial position to 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6472
2014-10-09 10:29:05 41668 [Note] WSREP: protonet asio version 0
2014-10-09 10:29:05 41668 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:29:05 41668 [Note] WSREP: backend: asio
2014-10-09 10:29:05 41668 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:29:05 41668 [Note] WSREP: restore pc from disk failed
2014-10-09 10:29:05 41668 [Note] WSREP: GMCast version 0
2014-10-09 10:29:05 41668 [Note] WSREP: (f2f11395, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:29:05 41668 [Note] WSREP: (f2f11395, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:29:05 41668 [Note] WSREP: EVS version 0
2014-10-09 10:29:05 41668 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:29:05 41668 [Note] WSREP: (f2f11395, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:29:05 41668 [Note] WSREP: declaring f08b5250 at tcp://127.0.0.1:13001 stable
2014-10-09 10:29:05 41668 [Note] WSREP: Node f08b5250 state prim
2014-10-09 10:29:05 41668 [Note] WSREP: save pc into disk
2014-10-09 10:29:06 41668 [Note] WSREP: gcomm: connected
2014-10-09 10:29:06 41668 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:29:06 41668 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:29:06 41668 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:29:06 41668 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:29:06 41668 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:29:06 41668 [Note] WSREP: STATE EXCHANGE: sent state msg: f341a175-4f85-11e4-8855-b2c5bc129aa5
2014-10-09 10:29:06 41668 [Note] WSREP: STATE EXCHANGE: got state msg: f341a175-4f85-11e4-8855-b2c5bc129aa5 from 0 (localhost.localdomain)
2014-10-09 10:29:06 41668 [Note] WSREP: STATE EXCHANGE: got state msg: f341a175-4f85-11e4-8855-b2c5bc129aa5 from 1 (localhost.localdomain)
2014-10-09 10:29:06 41668 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 4,
	members    = 1/2 (joined/total),
	act_id     = 6473,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d
2014-10-09 10:29:06 41668 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:29:06 41668 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 6473)
2014-10-09 10:29:06 41668 [Note] WSREP: State transfer required: 
	Group state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6473
	Local state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6472
2014-10-09 10:29:06 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6473, view# 5: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:29:06 41668 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:29:08 41668 [Note] WSREP: killing local connection: 262
2014-10-09 10:29:08 41668 [Note] WSREP: (f2f11395, 'tcp://0.0.0.0:13004') turning message relay requesting off
2014-10-09 10:29:08 41668 [Note] WSREP: Prepared SST request: mysqldump|127.0.0.1:13003
2014-10-09 10:29:08 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:29:08 41668 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:29:08 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:29:08 41668 [Note] WSREP: Assign initial position for certification: 6473, protocol version: 3
2014-10-09 10:29:08 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:29:08 41668 [Note] WSREP: Prepared IST receiver, listening at: tcp://127.0.0.1:13005
2014-10-09 10:29:08 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:29:08 41668 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 6473)
2014-10-09 10:29:08 41668 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:29:08 7f59f4314700 InnoDB: Error: Table "mysql"."innodb_table_stats" not found.
2014-10-09 10:29:08 7f59f4314700 InnoDB: Error: Table "mysql"."innodb_table_stats" not found.
2014-10-09 10:29:08 7f59f4314700 InnoDB: Error: Table "mysql"."innodb_table_stats" not found.
2014-10-09 10:29:08 41668 [Note] WSREP: wsrep_start_position var submitted: '78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6473'
2014-10-09 10:29:08 41668 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:29:08 41668 [Note] WSREP: SST received: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6473
2014-10-09 10:29:08 41668 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:29:08 41668 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:29:08 41668 [Note] WSREP: Shifting JOINER -> JOINED (TO: 6473)
2014-10-09 10:29:08 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:29:08 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:29:08 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 6473)
2014-10-09 10:29:08 41668 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:29:08 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
CURRENT_TEST: galera.galera_status_cluster
CURRENT_TEST: galera.galera_status_local_state
2014-10-09 10:29:17 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:29:17 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:29:17 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
CURRENT_TEST: galera.galera_suspend_slave
2014-10-09 10:29:24 41668 [Warning] WSREP: last inactive check more than PT1.5S ago (PT6.67976S), skipping check
2014-10-09 10:29:24 41668 [Note] WSREP: (f2f11395, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:29:27 41668 [Note] WSREP: (f2f11395, 'tcp://0.0.0.0:13004') turning message relay requesting off
2014-10-09 10:29:29 41668 [Note] WSREP: evs::proto(f2f11395, OPERATIONAL, view_id(REG,f08b5250,2)) suspecting node: f08b5250
2014-10-09 10:29:29 41668 [Note] WSREP: evs::proto(f2f11395, OPERATIONAL, view_id(REG,f08b5250,2)) suspected node without join message, declaring inactive
2014-10-09 10:29:30 41668 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:29:30 41668 [Note] WSREP: Flow-control interval: [16, 16]
2014-10-09 10:29:30 41668 [Note] WSREP: Received NON-PRIMARY.
2014-10-09 10:29:30 41668 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 6483)
2014-10-09 10:29:30 41668 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:29:30 41668 [Note] WSREP: Flow-control interval: [16, 16]
2014-10-09 10:29:30 41668 [Note] WSREP: Received NON-PRIMARY.
2014-10-09 10:29:30 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6483, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
2014-10-09 10:29:30 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:29:30 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6483, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
2014-10-09 10:29:30 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:29:30 41668 [Note] WSREP: declaring f08b5250 at tcp://127.0.0.1:13001 stable
2014-10-09 10:29:30 41668 [Note] WSREP: re-bootstrapping prim from partitioned components
2014-10-09 10:29:30 41668 [Note] WSREP: save pc into disk
2014-10-09 10:29:30 41668 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:29:30 41668 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:29:30 41668 [Note] WSREP: STATE EXCHANGE: sent state msg: 01b3cf9e-4f86-11e4-9999-cbbaaf9cd12e
2014-10-09 10:29:30 41668 [Note] WSREP: STATE EXCHANGE: got state msg: 01b3cf9e-4f86-11e4-9999-cbbaaf9cd12e from 0 (localhost.localdomain)
2014-10-09 10:29:30 41668 [Note] WSREP: STATE EXCHANGE: got state msg: 01b3cf9e-4f86-11e4-9999-cbbaaf9cd12e from 1 (localhost.localdomain)
2014-10-09 10:29:30 41668 [Warning] WSREP: Quorum: No node with complete state:


	Version      : 3
	Flags        : 0x3
	Protocols    : 0 / 6 / 3
	State        : NON-PRIMARY
	Prim state   : SYNCED
	Prim UUID    : f341a175-4f85-11e4-8855-b2c5bc129aa5
	Prim  seqno  : 5
	First seqno  : 6474
	Last  seqno  : 6483
	Prim JOINED  : 2
	State UUID   : 01b3cf9e-4f86-11e4-9999-cbbaaf9cd12e
	Group UUID   : 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d
	Name         : 'localhost.localdomain'
	Incoming addr: '127.0.0.1:13000'

	Version      : 3
	Flags        : 0x2
	Protocols    : 0 / 6 / 3
	State        : NON-PRIMARY
	Prim state   : SYNCED
	Prim UUID    : f341a175-4f85-11e4-8855-b2c5bc129aa5
	Prim  seqno  : 5
	First seqno  : 6474
	Last  seqno  : 6483
	Prim JOINED  : 2
	State UUID   : 01b3cf9e-4f86-11e4-9999-cbbaaf9cd12e
	Group UUID   : 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d
	Name         : 'localhost.localdomain'
	Incoming addr: '127.0.0.1:13003'

2014-10-09 10:29:30 41668 [Note] WSREP: Full re-merge of primary f341a175-4f85-11e4-8855-b2c5bc129aa5 found: 2 of 2.
2014-10-09 10:29:30 41668 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 5,
	members    = 2/2 (joined/total),
	act_id     = 6483,
	last_appl. = 0,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d
2014-10-09 10:29:30 41668 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:29:30 41668 [Note] WSREP: Restored state OPEN -> SYNCED (6483)
2014-10-09 10:29:30 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6483, view# 6: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:29:30 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:29:30 41668 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:29:30 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:29:30 41668 [Note] WSREP: Assign initial position for certification: 6483, protocol version: 3
2014-10-09 10:29:30 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:29:30 41668 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:29:30 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
CURRENT_TEST: galera.galera_toi_alter_auto_increment
CURRENT_TEST: galera.galera_toi_ddl_error
CURRENT_TEST: galera.galera_toi_ddl_fk_insert
CURRENT_TEST: galera.galera_toi_ddl_fk_update
CURRENT_TEST: galera.galera_toi_ddl_nonconflicting
CURRENT_TEST: galera.galera_toi_ddl_online
CURRENT_TEST: galera.galera_toi_ddl_sequential
CURRENT_TEST: galera.galera_toi_drop_database
CURRENT_TEST: galera.galera_toi_ftwrl
2014-10-09 10:31:19 41668 [Note] WSREP: Provider paused at 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6581 (124)
2014-10-09 10:31:19 41668 [Note] WSREP: resuming provider at 124
2014-10-09 10:31:19 41668 [Note] WSREP: Provider resumed.
CURRENT_TEST: galera.galera_toi_lock_exclusive
CURRENT_TEST: galera.galera_toi_lock_shared
CURRENT_TEST: galera.galera_toi_truncate
CURRENT_TEST: galera.galera_transaction_read_only
CURRENT_TEST: galera.galera_truncate
CURRENT_TEST: galera.galera_unicode_identifiers
CURRENT_TEST: galera.galera_unicode_pk
CURRENT_TEST: galera.galera_update_limit
CURRENT_TEST: galera.galera_var_auto_inc_control_off
CURRENT_TEST: galera.galera_var_auto_inc_control_on
CURRENT_TEST: galera.galera_var_certify_nonPK_off
CURRENT_TEST: galera.galera_var_fkchecks
CURRENT_TEST: galera.galera_var_load_data_splitting
CURRENT_TEST: galera.galera_var_mysql_replication_bundle
CURRENT_TEST: galera.galera_var_replicate_myisam_off
CURRENT_TEST: galera.galera_var_replicate_myisam_on
CURRENT_TEST: galera.galera_var_slave_threads
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:02 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:02 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:03 41668 [Note] WSREP: Slave thread exiting on request.
2014-10-09 10:32:03 41668 [Note] WSREP: applier thread exiting (code:0)
CURRENT_TEST: galera.galera_var_sync_wait
CURRENT_TEST: galera.galera_wsrep_desync_wsrep_on
2014-10-09 10:32:05 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:32:05 41668 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 6818)
2014-10-09 10:32:06 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:32:06 41668 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 6818)
2014-10-09 10:32:06 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:32:06 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 6818)
2014-10-09 10:32:06 41668 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:32:08 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) desyncs itself from group
2014-10-09 10:32:10 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) resyncs itself to group
2014-10-09 10:32:10 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
CURRENT_TEST: galera.galera_wsrep_provider_unset_set
2014-10-09 10:32:10 41668 [Note] WSREP: Stop replication
2014-10-09 10:32:10 41668 [Note] WSREP: Closing send monitor...
2014-10-09 10:32:10 41668 [Note] WSREP: Closed send monitor.
2014-10-09 10:32:10 41668 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:32:10 41668 [Note] WSREP: gcomm: joining thread
2014-10-09 10:32:10 41668 [Note] WSREP: gcomm: closing backend
2014-10-09 10:32:11 41668 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:32:11 41668 [Note] WSREP: gcomm: closed
2014-10-09 10:32:11 41668 [Note] WSREP: Flow-control interval: [16, 16]
2014-10-09 10:32:11 41668 [Note] WSREP: Received NON-PRIMARY.
2014-10-09 10:32:11 41668 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 6825)
2014-10-09 10:32:11 41668 [Note] WSREP: Received self-leave message.
2014-10-09 10:32:11 41668 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:32:11 41668 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:32:11 41668 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 6825)
2014-10-09 10:32:11 41668 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:32:11 41668 [Note] WSREP: recv_thread() joined.
2014-10-09 10:32:11 41668 [Note] WSREP: Closing replication queue.
2014-10-09 10:32:11 41668 [Note] WSREP: Closing slave action queue.
2014-10-09 10:32:11 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6825, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
2014-10-09 10:32:11 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:11 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6825, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:32:11 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:11 41668 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:13 41668 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:32:13 41668 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:32:13 41668 [Note] WSREP: mon: entered 352 oooe fraction 0 oool fraction 0
2014-10-09 10:32:13 41668 [Note] WSREP: mon: entered 352 oooe fraction 0.196023 oool fraction 0
2014-10-09 10:32:13 41668 [Note] WSREP: mon: entered 385 oooe fraction 0 oool fraction 0.0025974
2014-10-09 10:32:13 41668 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:32:13 41668 [Note] WSREP: cert trx map usage at exit 7
2014-10-09 10:32:13 41668 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:32:13 41668 [Note] WSREP: avg deps dist 7.03409
2014-10-09 10:32:13 41668 [Note] WSREP: avg cert interval 0.03125
2014-10-09 10:32:13 41668 [Note] WSREP: cert index size 100006
2014-10-09 10:32:13 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:13 41668 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:32:13 41668 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0.605769, misses: 41, in use: 0, in pool: 41
2014-10-09 10:32:13 41668 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0.640316, misses: 91, in use: 0, in pool: 91
2014-10-09 10:32:13 41668 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 6825)
2014-10-09 10:32:13 41668 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:32:13 41668 [Note] WSREP: Initial position: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6825
2014-10-09 10:32:13 41668 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:32:13 41668 [Note] WSREP: wsrep_load(): loading provider library 'none'
2014-10-09 10:32:14 41668 [Note] WSREP: Stop replication
2014-10-09 10:32:16 41668 [Note] WSREP: Initial position: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6825
2014-10-09 10:32:16 41668 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:32:16 41668 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:32:16 41668 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:32:16 41668 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:32:16 41668 [Note] WSREP: Found saved state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6825
2014-10-09 10:32:16 41668 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; cert.log_conflicts = no; debug = no; evs.causal_keepalive_period = PT1S; evs.debug_log_mask = 0x1; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.info_log_mask = 0; evs.install_timeout = PT7.5S; evs.join_retrans_period = PT1S; evs.keepalive_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.use_aggregate = true; evs.user_send_window = 2; evs.version = 0; evs.view_forget_timeout = P1D; gcache.dir = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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_sof
2014-10-09 10:32:16 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:16 41668 [Note] WSREP: Assign initial position for certification: 6825, protocol version: -1
2014-10-09 10:32:16 41668 [Note] WSREP: Stop replication
2014-10-09 10:32:18 41668 [Note] WSREP: Start replication
2014-10-09 10:32:18 41668 [Note] WSREP: Setting initial position to 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6825
2014-10-09 10:32:18 41668 [Note] WSREP: protonet asio version 0
2014-10-09 10:32:18 41668 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:32:18 41668 [Note] WSREP: backend: asio
2014-10-09 10:32:18 41668 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:32:18 41668 [Note] WSREP: restore pc from disk failed
2014-10-09 10:32:18 41668 [Note] WSREP: GMCast version 0
2014-10-09 10:32:18 41668 [Note] WSREP: (6630ecf0, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:32:18 41668 [Note] WSREP: (6630ecf0, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:32:18 41668 [Note] WSREP: EVS version 0
2014-10-09 10:32:18 41668 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:32:18 41668 [Note] WSREP: (6630ecf0, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:32:19 41668 [Note] WSREP: declaring f08b5250 at tcp://127.0.0.1:13001 stable
2014-10-09 10:32:19 41668 [Note] WSREP: Node f08b5250 state prim
2014-10-09 10:32:19 41668 [Note] WSREP: save pc into disk
2014-10-09 10:32:19 41668 [Note] WSREP: gcomm: connected
2014-10-09 10:32:19 41668 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:32:19 41668 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:32:19 41668 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:32:19 41668 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2
2014-10-09 10:32:19 41668 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 66d237e0-4f86-11e4-907a-63713fb35414
2014-10-09 10:32:19 41668 [Note] WSREP: STATE EXCHANGE: sent state msg: 66d237e0-4f86-11e4-907a-63713fb35414
2014-10-09 10:32:19 41668 [Note] WSREP: STATE EXCHANGE: got state msg: 66d237e0-4f86-11e4-907a-63713fb35414 from 1 (localhost.localdomain)
2014-10-09 10:32:19 41668 [Note] WSREP: STATE EXCHANGE: got state msg: 66d237e0-4f86-11e4-907a-63713fb35414 from 0 (localhost.localdomain)
2014-10-09 10:32:19 41668 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 7,
	members    = 1/2 (joined/total),
	act_id     = 6826,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d
2014-10-09 10:32:19 41668 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:32:19 41668 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 6826)
2014-10-09 10:32:19 41668 [Note] WSREP: State transfer required: 
	Group state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6826
	Local state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6825
2014-10-09 10:32:19 41668 [Note] WSREP: New cluster view: global state: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6826, view# 8: Primary, number of nodes: 2, my index: 0, protocol version 3
2014-10-09 10:32:19 41668 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:32:19 41668 [Note] WSREP: You have configured 'rsync' state snapshot transfer method which cannot be performed on a running server. Wsrep provider won't be able to fall back to it if other means of state transfer are unavailable. In that case you will need to restart the server.
2014-10-09 10:32:19 41668 [Warning] WSREP: Unsupported protocol downgrade: incremental data collection disabled. Expect abort.
2014-10-09 10:32:19 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:19 41668 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:32:19 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:19 41668 [Note] WSREP: Assign initial position for certification: 6826, protocol version: 3
2014-10-09 10:32:19 41668 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:19 41668 [Note] WSREP: Prepared IST receiver, listening at: tcp://127.0.0.1:13005
2014-10-09 10:32:19 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 1.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:32:19 41668 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 6826)
2014-10-09 10:32:19 41668 [Note] WSREP: Requesting state transfer: success, donor: 1
2014-10-09 10:32:19 41668 [Note] WSREP: Receiving IST: 1 writesets, seqnos 6825-6826
2014-10-09 10:32:19 41668 [Note] WSREP: 1.0 (localhost.localdomain): State transfer to 0.0 (localhost.localdomain) complete.
2014-10-09 10:32:19 41668 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:32:19 41668 [Note] WSREP: IST received: 78f9fc30-4f85-11e4-ae92-f27cb2bd7a0d:6826
2014-10-09 10:32:19 41668 [Note] WSREP: 0.0 (localhost.localdomain): State transfer from 1.0 (localhost.localdomain) complete.
2014-10-09 10:32:19 41668 [Note] WSREP: Shifting JOINER -> JOINED (TO: 6826)
2014-10-09 10:32:19 41668 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:32:19 41668 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 6826)
2014-10-09 10:32:19 41668 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:32:19 41668 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:21 41668 [Note] WSREP: (6630ecf0, 'tcp://0.0.0.0:13004') turning message relay requesting off
CURRENT_TEST: galera.galera_wsrep_provider_unset_set
2014-10-09 10:32:30 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2014-10-09 10:32:30 0 [Warning] WSREP: option --wsrep-casual-reads is deprecated
2014-10-09 10:32:30 44051 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-10-09 10:32:30 44051 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:32:30 44051 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:32:30 44051 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:32:30 44051 [Warning] WSREP: Could not open saved state file for reading: /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data//grastate.dat
2014-10-09 10:32:30 44051 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:32:30 44051 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; 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/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = f
2014-10-09 10:32:30 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:30 44051 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2014-10-09 10:32:30 44051 [Note] WSREP: wsrep_sst_grab()
2014-10-09 10:32:30 44051 [Note] WSREP: Start replication
2014-10-09 10:32:30 44051 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:32:30 44051 [Note] WSREP: protonet asio version 0
2014-10-09 10:32:30 44051 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:32:30 44051 [Note] WSREP: backend: asio
2014-10-09 10:32:30 44051 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:32:30 44051 [Note] WSREP: restore pc from disk failed
2014-10-09 10:32:30 44051 [Note] WSREP: GMCast version 0
2014-10-09 10:32:30 44051 [Note] WSREP: (6d8625ee, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:32:30 44051 [Note] WSREP: (6d8625ee, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:32:30 44051 [Note] WSREP: EVS version 0
2014-10-09 10:32:30 44051 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:32:30 44051 [Note] WSREP: (6d8625ee, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:32:31 44051 [Note] WSREP: declaring 6d4cf743 at tcp://127.0.0.1:13001 stable
2014-10-09 10:32:31 44051 [Note] WSREP: Node 6d4cf743 state prim
2014-10-09 10:32:31 44051 [Note] WSREP: save pc into disk
2014-10-09 10:32:31 44051 [Note] WSREP: gcomm: connected
2014-10-09 10:32:31 44051 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:32:31 44051 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:32:31 44051 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:32:31 44051 [Note] WSREP: Waiting for SST to complete.
2014-10-09 10:32:31 44051 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:32:31 44051 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:32:31 44051 [Note] WSREP: STATE EXCHANGE: sent state msg: 6dd37b1a-4f86-11e4-8bba-a25c433b440c
2014-10-09 10:32:31 44051 [Note] WSREP: STATE EXCHANGE: got state msg: 6dd37b1a-4f86-11e4-8bba-a25c433b440c from 0 (localhost.localdomain)
2014-10-09 10:32:31 44051 [Note] WSREP: STATE EXCHANGE: got state msg: 6dd37b1a-4f86-11e4-8bba-a25c433b440c from 1 (localhost.localdomain)
2014-10-09 10:32:31 44051 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 1,
	members    = 1/2 (joined/total),
	act_id     = 0,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc
2014-10-09 10:32:31 44051 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:32:31 44051 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2014-10-09 10:32:31 44051 [Note] WSREP: State transfer required: 
	Group state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:0
	Local state: 00000000-0000-0000-0000-000000000000:-1
2014-10-09 10:32:31 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:32:31 44051 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:32:31 44051 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1' --auth '' --datadir '/home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/' --defaults-file '/home/philips/git/codership-mysql/mysql-test/var/my.cnf' --parent '44051'  '' '
/home/philips/git/codership-mysql/scripts/wsrep_sst_common: line 98: my_print_defaults: command not found
/home/philips/git/codership-mysql/scripts/wsrep_sst_rsync: line 94: /home/philips/git/codership-mysql/scripts/my_print_defaults: No such file or directory
2014-10-09 10:32:32 44051 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:4444/rsync_sst
2014-10-09 10:32:32 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:32 44051 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:32:32 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:32 44051 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2014-10-09 10:32:32 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:32 44051 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc): 1 (Operation not permitted)
	 at galera/src/replicator_str.cpp:prepare_for_IST():456. IST will be unavailable.
2014-10-09 10:32:32 44051 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:32:32 44051 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2014-10-09 10:32:32 44051 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:32:33 44051 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:32:33 44051 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:32:34 44051 [Note] WSREP: (6d8625ee, 'tcp://0.0.0.0:13004') turning message relay requesting off
WSREP_SST: [INFO] Joiner cleanup. (20141009 10:32:34.209)
WSREP_SST: [INFO] Joiner cleanup done. (20141009 10:32:34.744)
2014-10-09 10:32:34 44051 [Note] WSREP: SST complete, seqno: 0
2014-10-09 10:32:34 44051 [Note] Plugin 'FEDERATED' is disabled.
2014-10-09 10:32:34 7f6bfd7a1780 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2014-10-09 10:32:34 44051 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-10-09 10:32:34 44051 [Note] InnoDB: The InnoDB memory heap is disabled
2014-10-09 10:32:34 44051 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-10-09 10:32:34 44051 [Note] InnoDB: Memory barrier is not used
2014-10-09 10:32:34 44051 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-10-09 10:32:34 44051 [Note] InnoDB: Using Linux native AIO
2014-10-09 10:32:34 44051 [Note] InnoDB: Using CPU crc32 instructions
2014-10-09 10:32:34 44051 [Note] InnoDB: Initializing buffer pool, size = 8.0M
2014-10-09 10:32:34 44051 [Note] InnoDB: Completed initialization of buffer pool
2014-10-09 10:32:34 44051 [Note] InnoDB: Highest supported file format is Barracuda.
2014-10-09 10:32:34 44051 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1625977
2014-10-09 10:32:34 44051 [Note] InnoDB: Database was not shutdown normally!
2014-10-09 10:32:34 44051 [Note] InnoDB: Starting crash recovery.
2014-10-09 10:32:34 44051 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-10-09 10:32:34 44051 [Note] InnoDB: Restoring possible half-written data pages 
2014-10-09 10:32:34 44051 [Note] InnoDB: from the doublewrite buffer...
InnoDB: Doing recovery: scanned up to log sequence number 1626167
2014-10-09 10:32:34 44051 [Note] InnoDB: 128 rollback segment(s) are active.
2014-10-09 10:32:34 44051 [Note] InnoDB: Waiting for purge to start
2014-10-09 10:32:34 44051 [Note] InnoDB: 5.6.21 started; log sequence number 1626167
2014-10-09 10:32:34 44051 [Warning] /home/philips/git/codership-mysql/sql/mysqld: unknown variable 'loose-debug-sync-timeout=600'
2014-10-09 10:32:34 44051 [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: 6fe68e66-4f86-11e4-8a53-000c29102922.
2014-10-09 10:32:34 44051 [Note] Server hostname (bind-address): '*'; port: 13003
2014-10-09 10:32:34 44051 [Note] IPv6 is available.
2014-10-09 10:32:34 44051 [Note]   - '::' resolves to '::';
2014-10-09 10:32:34 44051 [Note] Server socket created on IP: '::'.
2014-10-09 10:32:34 44051 [Note] Event Scheduler: Loaded 0 events
2014-10-09 10:32:34 44051 [Note] WSREP: Signalling provider to continue.
2014-10-09 10:32:34 44051 [Note] WSREP: inited wsrep sidno 1
2014-10-09 10:32:34 44051 [Note] WSREP: SST received: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:0
2014-10-09 10:32:34 44051 [Note] /home/philips/git/codership-mysql/sql/mysqld: ready for connections.
Version: '5.6.21-log'  socket: '/home/philips/git/codership-mysql/mysql-test/var/tmp/mysqld.2.sock'  port: 13003  , wsrep_25.6.r4149
2014-10-09 10:32:34 44051 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:32:34 44051 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2014-10-09 10:32:34 44051 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:32:34 44051 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2014-10-09 10:32:34 44051 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:32:34 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:35 44051 [Note] WSREP: Stop replication
2014-10-09 10:32:35 44051 [Note] WSREP: Closing send monitor...
2014-10-09 10:32:35 44051 [Note] WSREP: Closed send monitor.
2014-10-09 10:32:35 44051 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:32:35 44051 [Note] WSREP: gcomm: joining thread
2014-10-09 10:32:35 44051 [Note] WSREP: gcomm: closing backend
2014-10-09 10:32:36 44051 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:32:36 44051 [Note] WSREP: gcomm: closed
2014-10-09 10:32:36 44051 [Note] WSREP: Flow-control interval: [16, 16]
2014-10-09 10:32:36 44051 [Note] WSREP: Received NON-PRIMARY.
2014-10-09 10:32:36 44051 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 2)
2014-10-09 10:32:36 44051 [Note] WSREP: Received self-leave message.
2014-10-09 10:32:36 44051 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:32:36 44051 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:32:36 44051 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 2)
2014-10-09 10:32:36 44051 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:32:36 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:2, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
2014-10-09 10:32:36 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:36 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:2, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:32:36 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:36 44051 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:36 44051 [Note] WSREP: recv_thread() joined.
2014-10-09 10:32:36 44051 [Note] WSREP: Closing replication queue.
2014-10-09 10:32:36 44051 [Note] WSREP: Closing slave action queue.
2014-10-09 10:32:38 44051 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:32:38 44051 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:32:38 44051 [Note] WSREP: mon: entered 2 oooe fraction 0 oool fraction 0
2014-10-09 10:32:38 44051 [Note] WSREP: mon: entered 2 oooe fraction 0 oool fraction 0
2014-10-09 10:32:38 44051 [Note] WSREP: mon: entered 7 oooe fraction 0 oool fraction 0.142857
2014-10-09 10:32:38 44051 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:32:38 44051 [Note] WSREP: cert trx map usage at exit 2
2014-10-09 10:32:38 44051 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:32:38 44051 [Note] WSREP: avg deps dist 1
2014-10-09 10:32:38 44051 [Note] WSREP: avg cert interval 0
2014-10-09 10:32:38 44051 [Note] WSREP: cert index size 3
2014-10-09 10:32:38 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:38 44051 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:32:38 44051 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0
2014-10-09 10:32:38 44051 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 2, in use: 0, in pool: 2
2014-10-09 10:32:38 44051 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 2)
2014-10-09 10:32:38 44051 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:32:38 44051 [Note] WSREP: Initial position: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:2
2014-10-09 10:32:38 44051 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:32:38 44051 [Note] WSREP: wsrep_load(): loading provider library 'none'
2014-10-09 10:32:39 44051 [Note] WSREP: Stop replication
2014-10-09 10:32:41 44051 [Note] WSREP: Initial position: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:2
2014-10-09 10:32:41 44051 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:32:41 44051 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:32:41 44051 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:32:41 44051 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:32:41 44051 [Note] WSREP: Found saved state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:2
2014-10-09 10:32:41 44051 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; cert.log_conflicts = no; debug = no; evs.causal_keepalive_period = PT1S; evs.debug_log_mask = 0x1; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.info_log_mask = 0; evs.install_timeout = PT7.5S; evs.join_retrans_period = PT1S; evs.keepalive_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.use_aggregate = true; evs.user_send_window = 2; evs.version = 0; evs.view_forget_timeout = P1D; gcache.dir = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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_sof
2014-10-09 10:32:41 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:41 44051 [Note] WSREP: Assign initial position for certification: 2, protocol version: -1
2014-10-09 10:32:41 44051 [Note] WSREP: Stop replication
2014-10-09 10:32:43 44051 [Note] WSREP: Start replication
2014-10-09 10:32:43 44051 [Note] WSREP: Setting initial position to 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:2
2014-10-09 10:32:43 44051 [Note] WSREP: protonet asio version 0
2014-10-09 10:32:43 44051 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:32:43 44051 [Note] WSREP: backend: asio
2014-10-09 10:32:43 44051 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:32:43 44051 [Note] WSREP: restore pc from disk failed
2014-10-09 10:32:43 44051 [Note] WSREP: GMCast version 0
2014-10-09 10:32:43 44051 [Note] WSREP: (74d25a46, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:32:43 44051 [Note] WSREP: (74d25a46, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:32:43 44051 [Note] WSREP: EVS version 0
2014-10-09 10:32:43 44051 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:32:43 44051 [Note] WSREP: (74d25a46, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:32:43 44051 [Note] WSREP: declaring 6d4cf743 at tcp://127.0.0.1:13001 stable
2014-10-09 10:32:43 44051 [Note] WSREP: Node 6d4cf743 state prim
2014-10-09 10:32:43 44051 [Note] WSREP: save pc into disk
2014-10-09 10:32:44 44051 [Note] WSREP: gcomm: connected
2014-10-09 10:32:44 44051 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:32:44 44051 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:32:44 44051 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:32:44 44051 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:32:44 44051 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:32:44 44051 [Note] WSREP: STATE EXCHANGE: sent state msg: 75206060-4f86-11e4-8a23-77d02d5cdf8c
2014-10-09 10:32:44 44051 [Note] WSREP: STATE EXCHANGE: got state msg: 75206060-4f86-11e4-8a23-77d02d5cdf8c from 0 (localhost.localdomain)
2014-10-09 10:32:44 44051 [Note] WSREP: STATE EXCHANGE: got state msg: 75206060-4f86-11e4-8a23-77d02d5cdf8c from 1 (localhost.localdomain)
2014-10-09 10:32:44 44051 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 3,
	members    = 1/2 (joined/total),
	act_id     = 3,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc
2014-10-09 10:32:44 44051 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:32:44 44051 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 3)
2014-10-09 10:32:44 44051 [Note] WSREP: State transfer required: 
	Group state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:3
	Local state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:2
2014-10-09 10:32:44 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:3, view# 4: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:32:44 44051 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:32:44 44051 [Note] WSREP: You have configured 'rsync' state snapshot transfer method which cannot be performed on a running server. Wsrep provider won't be able to fall back to it if other means of state transfer are unavailable. In that case you will need to restart the server.
2014-10-09 10:32:44 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:44 44051 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:32:44 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:44 44051 [Note] WSREP: Assign initial position for certification: 3, protocol version: 3
2014-10-09 10:32:44 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:32:44 44051 [Note] WSREP: Prepared IST receiver, listening at: tcp://127.0.0.1:13005
2014-10-09 10:32:44 44051 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:32:44 44051 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 3)
2014-10-09 10:32:44 44051 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:32:44 44051 [Note] WSREP: Receiving IST: 1 writesets, seqnos 2-3
2014-10-09 10:32:44 44051 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:32:44 44051 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:32:44 44051 [Note] WSREP: IST received: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:3
2014-10-09 10:32:44 44051 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:32:44 44051 [Note] WSREP: Shifting JOINER -> JOINED (TO: 3)
2014-10-09 10:32:44 44051 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:32:44 44051 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 3)
2014-10-09 10:32:44 44051 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:32:44 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:46 44051 [Note] WSREP: (74d25a46, 'tcp://0.0.0.0:13004') turning message relay requesting off
CURRENT_TEST: galera.galera_wsrep_provider_unset_set
2014-10-09 10:32:57 44051 [Note] WSREP: Stop replication
2014-10-09 10:32:57 44051 [Note] WSREP: Closing send monitor...
2014-10-09 10:32:57 44051 [Note] WSREP: Closed send monitor.
2014-10-09 10:32:57 44051 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:32:57 44051 [Note] WSREP: gcomm: joining thread
2014-10-09 10:32:57 44051 [Note] WSREP: gcomm: closing backend
2014-10-09 10:32:58 44051 [Note] WSREP: gcomm: closed
2014-10-09 10:32:58 44051 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:32:58 44051 [Note] WSREP: Flow-control interval: [16, 16]
2014-10-09 10:32:58 44051 [Note] WSREP: Received NON-PRIMARY.
2014-10-09 10:32:58 44051 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 9)
2014-10-09 10:32:58 44051 [Note] WSREP: Received self-leave message.
2014-10-09 10:32:58 44051 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:32:58 44051 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:32:58 44051 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 9)
2014-10-09 10:32:58 44051 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:32:58 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:9, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
2014-10-09 10:32:58 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:58 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:9, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:32:58 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:32:58 44051 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:32:58 44051 [Note] WSREP: recv_thread() joined.
2014-10-09 10:32:58 44051 [Note] WSREP: Closing replication queue.
2014-10-09 10:32:58 44051 [Note] WSREP: Closing slave action queue.
2014-10-09 10:33:00 44051 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:33:00 44051 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:33:00 44051 [Note] WSREP: mon: entered 7 oooe fraction 0 oool fraction 0
2014-10-09 10:33:00 44051 [Note] WSREP: mon: entered 7 oooe fraction 0 oool fraction 0
2014-10-09 10:33:00 44051 [Note] WSREP: mon: entered 11 oooe fraction 0 oool fraction 0.0909091
2014-10-09 10:33:00 44051 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:33:00 44051 [Note] WSREP: cert trx map usage at exit 6
2014-10-09 10:33:00 44051 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:33:00 44051 [Note] WSREP: avg deps dist 1
2014-10-09 10:33:00 44051 [Note] WSREP: avg cert interval 0
2014-10-09 10:33:00 44051 [Note] WSREP: cert index size 6
2014-10-09 10:33:00 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:33:00 44051 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:33:00 44051 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 2, in use: 0, in pool: 2
2014-10-09 10:33:00 44051 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0.2, misses: 4, in use: 0, in pool: 4
2014-10-09 10:33:00 44051 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 9)
2014-10-09 10:33:00 44051 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:33:00 44051 [Note] WSREP: Initial position: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:9
2014-10-09 10:33:00 44051 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:33:00 44051 [Note] WSREP: wsrep_load(): loading provider library 'none'
2014-10-09 10:33:01 44051 [Note] WSREP: Stop replication
2014-10-09 10:33:03 44051 [Note] WSREP: Initial position: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:9
2014-10-09 10:33:03 44051 [Warning] WSREP: Initial position was provided by configuration or SST, avoiding override
2014-10-09 10:33:03 44051 [Note] WSREP: wsrep_load(): loading provider library '/home/philips/git/galera/libgalera_smm.so'
2014-10-09 10:33:03 44051 [Note] WSREP: wsrep_load(): Galera 3.8(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
2014-10-09 10:33:03 44051 [Note] WSREP: CRC-32C: using hardware acceleration.
2014-10-09 10:33:03 44051 [Note] WSREP: Found saved state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:9
2014-10-09 10:33:03 44051 [Note] WSREP: Passing config to GCS: base_host = 127.0.0.1; base_port = 13004; cert.log_conflicts = no; debug = no; evs.causal_keepalive_period = PT1S; evs.debug_log_mask = 0x1; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.info_log_mask = 0; evs.install_timeout = PT7.5S; evs.join_retrans_period = PT1S; evs.keepalive_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.use_aggregate = true; evs.user_send_window = 2; evs.version = 0; evs.view_forget_timeout = P1D; gcache.dir = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/philips/git/codership-mysql/mysql-test/var/mysqld.2/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_sof
2014-10-09 10:33:03 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:33:03 44051 [Note] WSREP: Assign initial position for certification: 9, protocol version: -1
2014-10-09 10:33:03 44051 [Note] WSREP: Stop replication
2014-10-09 10:33:05 44051 [Note] WSREP: Start replication
2014-10-09 10:33:05 44051 [Note] WSREP: Setting initial position to 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:9
2014-10-09 10:33:05 44051 [Note] WSREP: protonet asio version 0
2014-10-09 10:33:05 44051 [Note] WSREP: Using CRC-32C for message checksums.
2014-10-09 10:33:05 44051 [Note] WSREP: backend: asio
2014-10-09 10:33:05 44051 [Warning] WSREP: access file(gvwstate.dat) failed(No such file or directory)
2014-10-09 10:33:05 44051 [Note] WSREP: restore pc from disk failed
2014-10-09 10:33:05 44051 [Note] WSREP: GMCast version 0
2014-10-09 10:33:05 44051 [Note] WSREP: (821ee9ed, 'tcp://0.0.0.0:13004') listening at tcp://0.0.0.0:13004
2014-10-09 10:33:05 44051 [Note] WSREP: (821ee9ed, 'tcp://0.0.0.0:13004') multicast: , ttl: 1
2014-10-09 10:33:05 44051 [Note] WSREP: EVS version 0
2014-10-09 10:33:05 44051 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:13001'
2014-10-09 10:33:05 44051 [Note] WSREP: (821ee9ed, 'tcp://0.0.0.0:13004') turning message relay requesting on, nonlive peers: 
2014-10-09 10:33:06 44051 [Note] WSREP: declaring 6d4cf743 at tcp://127.0.0.1:13001 stable
2014-10-09 10:33:06 44051 [Note] WSREP: Node 6d4cf743 state prim
2014-10-09 10:33:06 44051 [Note] WSREP: save pc into disk
2014-10-09 10:33:06 44051 [Note] WSREP: gcomm: connected
2014-10-09 10:33:06 44051 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2014-10-09 10:33:06 44051 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2014-10-09 10:33:06 44051 [Note] WSREP: Opened channel 'my_wsrep_cluster'
2014-10-09 10:33:06 44051 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2014-10-09 10:33:06 44051 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2014-10-09 10:33:06 44051 [Note] WSREP: STATE EXCHANGE: sent state msg: 826c6fc5-4f86-11e4-b633-87cac52306ef
2014-10-09 10:33:06 44051 [Note] WSREP: STATE EXCHANGE: got state msg: 826c6fc5-4f86-11e4-b633-87cac52306ef from 0 (localhost.localdomain)
2014-10-09 10:33:06 44051 [Note] WSREP: STATE EXCHANGE: got state msg: 826c6fc5-4f86-11e4-b633-87cac52306ef from 1 (localhost.localdomain)
2014-10-09 10:33:06 44051 [Note] WSREP: Quorum results:
	version    = 3,
	component  = PRIMARY,
	conf_id    = 5,
	members    = 1/2 (joined/total),
	act_id     = 10,
	last_appl. = -1,
	protocols  = 0/6/3 (gcs/repl/appl),
	group UUID = 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc
2014-10-09 10:33:06 44051 [Note] WSREP: Flow-control interval: [23, 23]
2014-10-09 10:33:06 44051 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 10)
2014-10-09 10:33:06 44051 [Note] WSREP: State transfer required: 
	Group state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:10
	Local state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:9
2014-10-09 10:33:06 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:10, view# 6: Primary, number of nodes: 2, my index: 1, protocol version 3
2014-10-09 10:33:06 44051 [Warning] WSREP: Gap in state sequence. Need state transfer.
2014-10-09 10:33:06 44051 [Note] WSREP: You have configured 'rsync' state snapshot transfer method which cannot be performed on a running server. Wsrep provider won't be able to fall back to it if other means of state transfer are unavailable. In that case you will need to restart the server.
2014-10-09 10:33:06 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:33:06 44051 [Note] WSREP: REPL Protocols: 6 (3, 2)
2014-10-09 10:33:06 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:33:06 44051 [Note] WSREP: Assign initial position for certification: 10, protocol version: 3
2014-10-09 10:33:06 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:33:06 44051 [Note] WSREP: Prepared IST receiver, listening at: tcp://127.0.0.1:13005
2014-10-09 10:33:06 44051 [Note] WSREP: Member 1.0 (localhost.localdomain) requested state transfer from '*any*'. Selected 0.0 (localhost.localdomain)(SYNCED) as donor.
2014-10-09 10:33:06 44051 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 10)
2014-10-09 10:33:06 44051 [Note] WSREP: Requesting state transfer: success, donor: 0
2014-10-09 10:33:06 44051 [Note] WSREP: Receiving IST: 1 writesets, seqnos 9-10
2014-10-09 10:33:06 44051 [Note] WSREP: 0.0 (localhost.localdomain): State transfer to 1.0 (localhost.localdomain) complete.
2014-10-09 10:33:06 44051 [Note] WSREP: Member 0.0 (localhost.localdomain) synced with group.
2014-10-09 10:33:06 44051 [Note] WSREP: IST received: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:10
2014-10-09 10:33:06 44051 [Note] WSREP: 1.0 (localhost.localdomain): State transfer from 0.0 (localhost.localdomain) complete.
2014-10-09 10:33:06 44051 [Note] WSREP: Shifting JOINER -> JOINED (TO: 10)
2014-10-09 10:33:06 44051 [Note] WSREP: Member 1.0 (localhost.localdomain) synced with group.
2014-10-09 10:33:06 44051 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 10)
2014-10-09 10:33:06 44051 [Note] WSREP: Synchronized with group, ready for connections
2014-10-09 10:33:06 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:33:08 44051 [Note] WSREP: (821ee9ed, 'tcp://0.0.0.0:13004') turning message relay requesting off
CURRENT_TEST: galera.lp1276424
CURRENT_TEST: galera.lp1347768
2014-10-09 10:33:17 44051 [Note] /home/philips/git/codership-mysql/sql/mysqld: Normal shutdown

2014-10-09 10:33:17 44051 [Note] WSREP: Stop replication
2014-10-09 10:33:17 44051 [Note] WSREP: Closing send monitor...
2014-10-09 10:33:17 44051 [Note] WSREP: Closed send monitor.
2014-10-09 10:33:17 44051 [Note] WSREP: gcomm: terminating thread
2014-10-09 10:33:17 44051 [Note] WSREP: gcomm: joining thread
2014-10-09 10:33:17 44051 [Note] WSREP: gcomm: closing backend
2014-10-09 10:33:18 44051 [Note] WSREP: forgetting 6d4cf743 (tcp://127.0.0.1:13001)
2014-10-09 10:33:18 44051 [Note] WSREP: Node 821ee9ed state prim
2014-10-09 10:33:18 44051 [Note] WSREP: save pc into disk
2014-10-09 10:33:18 44051 [Note] WSREP: forgetting 6d4cf743 (tcp://127.0.0.1:13001)
2014-10-09 10:33:18 44051 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2014-10-09 10:33:18 44051 [Note] WSREP: gcomm: closed
2014-10-09 10:33:18 44051 [Warning] WSREP: 0x7f6bc0037f30 down context(s) not set
2014-10-09 10:33:18 44051 [Warning] WSREP: Failed to send state UUID: -107 (Transport endpoint is not connected)
2014-10-09 10:33:18 44051 [Note] WSREP: Received self-leave message.
2014-10-09 10:33:18 44051 [Note] WSREP: Flow-control interval: [0, 0]
2014-10-09 10:33:18 44051 [Note] WSREP: Received SELF-LEAVE. Closing connection.
2014-10-09 10:33:18 44051 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 25)
2014-10-09 10:33:18 44051 [Note] WSREP: RECV thread exiting 0: Success
2014-10-09 10:33:18 44051 [Note] WSREP: New cluster view: global state: 6d4d4bd4-4f86-11e4-9a7a-1e7ad6512ebc:25, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
2014-10-09 10:33:18 44051 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-10-09 10:33:18 44051 [Note] WSREP: applier thread exiting (code:0)
2014-10-09 10:33:18 44051 [Note] WSREP: recv_thread() joined.
2014-10-09 10:33:18 44051 [Note] WSREP: Closing replication queue.
2014-10-09 10:33:18 44051 [Note] WSREP: Closing slave action queue.
2014-10-09 10:33:20 44051 [Note] WSREP: rollbacker thread exiting
2014-10-09 10:33:20 44051 [Note] Giving 0 client threads a chance to die gracefully
2014-10-09 10:33:20 44051 [Note] Event Scheduler: Purging the queue. 0 events
2014-10-09 10:33:20 44051 [Note] Shutting down slave threads
2014-10-09 10:33:20 44051 [Note] Forcefully disconnecting 0 remaining clients
2014-10-09 10:33:20 44051 [Note] WSREP: dtor state: CLOSED
2014-10-09 10:33:20 44051 [Note] WSREP: mon: entered 16 oooe fraction 0 oool fraction 0
2014-10-09 10:33:20 44051 [Note] WSREP: mon: entered 16 oooe fraction 0 oool fraction 0
2014-10-09 10:33:20 44051 [Note] WSREP: mon: entered 19 oooe fraction 0 oool fraction 0.0526316
2014-10-09 10:33:20 44051 [Note] WSREP: cert index usage at exit 0
2014-10-09 10:33:20 44051 [Note] WSREP: cert trx map usage at exit 15
2014-10-09 10:33:20 44051 [Note] WSREP: deps set usage at exit 0
2014-10-09 10:33:20 44051 [Note] WSREP: avg deps dist 1
2014-10-09 10:33:20 44051 [Note] WSREP: avg cert interval 0
2014-10-09 10:33:20 44051 [Note] WSREP: cert index size 9
2014-10-09 10:33:20 44051 [Note] WSREP: Service thread queue flushed.
2014-10-09 10:33:20 44051 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
2014-10-09 10:33:20 44051 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 5, in use: 0, in pool: 5
2014-10-09 10:33:20 44051 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0.0909091, misses: 10, in use: 0, in pool: 10
2014-10-09 10:33:20 44051 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 25)
2014-10-09 10:33:20 44051 [Note] WSREP: Flushing memory map to disk...
2014-10-09 10:33:20 44051 [Note] Binlog end
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'partition'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_METRICS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_CMPMEM'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_CMP'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_LOCKS'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'INNODB_TRX'
2014-10-09 10:33:20 44051 [Note] Shutting down plugin 'InnoDB'
2014-10-09 10:33:20 44051 [Note] InnoDB: FTS optimize thread exiting.
2014-10-09 10:33:20 44051 [Note] InnoDB: Starting shutdown...
2014-10-09 10:33:22 44051 [Note] InnoDB: Shutdown completed; log sequence number 1705167
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'BLACKHOLE'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'ARCHIVE'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'CSV'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'MEMORY'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'MyISAM'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'MRG_MYISAM'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'sha256_password'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'mysql_old_password'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'mysql_native_password'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'wsrep'
2014-10-09 10:33:22 44051 [Note] Shutting down plugin 'binlog'
2014-10-09 10:33:22 44051 [Note] /home/philips/git/codership-mysql/sql/mysqld: Shutdown complete