120514 17:10:26 mysqld_safe Starting mysqld daemon with databases from /opt/mysql-galera/data 120514 17:10:26 [Note] WSREP: Read nil XID from storage engines, skipping position init 120514 17:10:26 [Note] WSREP: wsrep_load(): loading provider library '/opt/mysql-galera/lib/plugin/libgalera_smm.so' 120514 17:10:26 [Note] WSREP: wsrep_load(): Galera 2.1dev(rXXXX) by Codership Oy loaded succesfully. 120514 17:10:26 [Note] WSREP: Found saved state: 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:122347 120514 17:10:26 [Note] WSREP: Reusing existing '/opt/mysql-galera/data//galera.cache'. 120514 17:10:26 [Note] WSREP: Passing config to GCS: base_host = 192.168.0.5; gcache.dir = /opt/mysql-galera/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /opt/mysql-galera/data//galera.cache; gcache.page_size = 128M; gcache.size = 128M; gcs.fc_debug = 0; gcs.fc_factor = 0.5; gcs.fc_limit = 16; gcs.fc_master_slave = NO; gcs.max_packet_size = 64500; gcs.max_throttle = 0.25; gcs.recv_q_hard_limit = 2147483647; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = NO; replicator.causal_read_timeout = PT30S; replicator.commit_order = 3 120514 17:10:26 [Note] WSREP: Assign initial position for certification: 122347, protocol version: -1 120514 17:10:26 [Note] WSREP: wsrep_sst_grab() 120514 17:10:26 [Note] WSREP: Start replication 120514 17:10:26 [Note] WSREP: Setting initial position to 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:122347 120514 17:10:26 [Note] WSREP: protonet asio version 0 120514 17:10:26 [Note] WSREP: backend: asio 120514 17:10:26 [Note] WSREP: GMCast version 0 120514 17:10:26 [Note] WSREP: (ef79e623-9dd6-11e1-0800-31b3defee94b, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 120514 17:10:26 [Note] WSREP: (ef79e623-9dd6-11e1-0800-31b3defee94b, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 120514 17:10:26 [Note] WSREP: EVS version 0 120514 17:10:26 [Note] WSREP: PC version 0 120514 17:10:26 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '192.168.0.1:4567' 120514 17:10:26 [Note] WSREP: declaring d3732dbe-9dd6-11e1-0800-565d7a5b05b6 stable 120514 17:10:26 [Note] WSREP: view(view_id(PRIM,d3732dbe-9dd6-11e1-0800-565d7a5b05b6,2) memb { d3732dbe-9dd6-11e1-0800-565d7a5b05b6, ef79e623-9dd6-11e1-0800-31b3defee94b, } joined { } left { } partitioned { }) 120514 17:10:27 [Note] WSREP: gcomm: connected 120514 17:10:27 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 120514 17:10:27 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 120514 17:10:27 [Note] WSREP: Opened channel 'my_wsrep_cluster' 120514 17:10:27 [Note] WSREP: Waiting for SST to complete. 120514 17:10:27 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2 120514 17:10:27 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID. 120514 17:10:27 [Note] WSREP: STATE EXCHANGE: sent state msg: ef78ce45-9dd6-11e1-0800-44de30008c38 120514 17:10:27 [Note] WSREP: STATE EXCHANGE: got state msg: ef78ce45-9dd6-11e1-0800-44de30008c38 from 0 (cygnus.lolomin.net) 120514 17:10:27 [Note] WSREP: STATE EXCHANGE: got state msg: ef78ce45-9dd6-11e1-0800-44de30008c38 from 1 (laurane.lolomin.net) 120514 17:10:27 [Note] WSREP: Quorum results: version = 2, component = PRIMARY, conf_id = 1, members = 1/2 (joined/total), act_id = 123252, last_appl. = -1, protocols = 0/4/1 (gcs/repl/appl), group UUID = 6cd33b89-9dc2-11e1-0800-c938ffc57dd8 120514 17:10:27 [Note] WSREP: Flow-control interval: [12, 23] 120514 17:10:27 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 123252) 120514 17:10:27 [Note] WSREP: State transfer required: Group state: 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:123252 Local state: 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:122347 120514 17:10:27 [Note] WSREP: New cluster view: global state: 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:123252, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 1 120514 17:10:27 [Warning] WSREP: Gap in state sequence. Need state transfer. 120514 17:10:29 [Note] WSREP: Running: 'wsrep_sst_rsync 'joiner' '192.168.0.5' 'sst:5T13wPid' '/opt/mysql-galera/data/' '/etc/my-galera.cnf' '18714' 2>sst.err' 120514 17:10:29 [Note] WSREP: Prepared SST request: rsync|192.168.0.5:4444/rsync_sst ERROR 2003 (HY000): Can't connect to MySQL server on 'laurane' (111) sed: couldn't flush stdout: Relais brisé (pipe) sed: couldn't flush stdout: Relais brisé (pipe) 120514 17:10:29 [Note] WSREP: Assign initial position for certification: 123252, protocol version: 2 120514 17:10:29 [Note] WSREP: Prepared IST receiver, listening at: tcp://192.168.0.5:4568 120514 17:10:29 [Note] WSREP: Node 1 (laurane.lolomin.net) requested state transfer from '*any*'. Selected 0 (cygnus.lolomin.net)(SYNCED) as donor. 120514 17:10:29 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 123274) 120514 17:10:29 [Note] WSREP: Requesting state transfer: success, donor: 0 120514 17:10:31 [Note] WSREP: SST complete, seqno: 122347 120514 17:10:31 InnoDB: The InnoDB memory heap is disabled 120514 17:10:31 InnoDB: Mutexes and rw_locks use GCC atomic builtins 120514 17:10:31 InnoDB: Compressed tables use zlib 1.2.3 120514 17:10:31 InnoDB: Using Linux native AIO 120514 17:10:31 InnoDB: Initializing buffer pool, size = 384.0M 120514 17:10:31 InnoDB: Completed initialization of buffer pool 120514 17:10:31 InnoDB: highest supported file format is Barracuda. InnoDB: The log sequence number in ibdata files does not match InnoDB: the log sequence number in the ib_logfiles! 120514 17:10:31 InnoDB: Database was not shut down normally! InnoDB: Starting crash recovery. InnoDB: Reading tablespace information from the .ibd files... InnoDB: Restoring possible half-written data pages from the doublewrite InnoDB: buffer... InnoDB: Last MySQL binlog file position 0 662842, file name /opt/mysql-galera/data/mysql-binary-log.000012 120514 17:10:31 InnoDB: Waiting for the background threads to start 120514 17:10:32 InnoDB: 1.1.8 started; log sequence number 21331078869 120514 17:10:32 [Note] Event Scheduler: Loaded 0 events 120514 17:10:32 [Note] WSREP: Signalling provider to continue. 120514 17:10:32 [Note] WSREP: Received SST: 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:122347 120514 17:10:32 [Note] WSREP: SST received: 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:122347 120514 17:10:32 [Note] WSREP: Receiving IST: 905 writesets, seqnos 122347-123252 120514 17:10:32 [Note] /opt/mysql-galera/bin/mysqld: ready for connections. Version: '5.5.23-log' socket: '/opt/mysql-galera/data/mysql.sock' port: 3306 Source distribution, wsrep_23.5.r3743 120514 17:10:35 [Note] WSREP: view(view_id(PRIM,ef79e623-9dd6-11e1-0800-31b3defee94b,3) memb { ef79e623-9dd6-11e1-0800-31b3defee94b, } joined { } left { } partitioned { d3732dbe-9dd6-11e1-0800-565d7a5b05b6, }) 120514 17:10:35 [Note] WSREP: forgetting d3732dbe-9dd6-11e1-0800-565d7a5b05b6 (tcp://192.168.0.1:4567) 120514 17:10:35 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1 120514 17:10:35 [Note] WSREP: STATE_EXCHANGE: sent state UUID: f4f95ebf-9dd6-11e1-0800-f38f630f3368 120514 17:10:35 [Note] WSREP: STATE EXCHANGE: sent state msg: f4f95ebf-9dd6-11e1-0800-f38f630f3368 120514 17:10:35 [Note] WSREP: STATE EXCHANGE: got state msg: f4f95ebf-9dd6-11e1-0800-f38f630f3368 from 0 (laurane.lolomin.net) 120514 17:10:35 [Warning] WSREP: Quorum: No node with complete state: Version : 2 Flags : 1 Protocols : 0 / 4 / 1 State : JOINER Prim state : JOINER Prim UUID : ef78ce45-9dd6-11e1-0800-44de30008c38 Prim seqno : 2 Last seqno : 123492 Prim JOINED : 1 State UUID : f4f95ebf-9dd6-11e1-0800-f38f630f3368 Group UUID : 6cd33b89-9dc2-11e1-0800-c938ffc57dd8 Name : 'laurane.lolomin.net' Incoming addr: '192.168.0.5:3306' 120514 17:10:35 [Warning] WSREP: No re-merged primary component found. 120514 17:10:35 [Warning] WSREP: No bootstrapped primary component found. 120514 17:10:35 [ERROR] WSREP: gcs/src/gcs_state_msg.c:gcs_state_msg_get_quorum():729: Failed to establish quorum. 120514 17:10:35 [Note] WSREP: Quorum results: version = 2, component = NON-PRIMARY, conf_id = -1, members = 1/1 (joined/total), act_id = -1, last_appl. = -1, protocols = -1/-1/-1 (gcs/repl/appl), group UUID = 00000000-0000-0000-0000-000000000000 120514 17:10:35 [Note] WSREP: Flow-control interval: [8, 16] 120514 17:10:35 [Note] WSREP: Received NON-PRIMARY. 120514 17:10:35 [Note] WSREP: Shifting JOINER -> OPEN (TO: 123492) 120514 17:10:40 [Note] WSREP: cleaning up d3732dbe-9dd6-11e1-0800-565d7a5b05b6 (tcp://192.168.0.1:4567) 120514 17:10:44 [Note] WSREP: Stop replication 120514 17:10:44 [Note] WSREP: Closing send monitor... 120514 17:10:44 [Note] WSREP: Closed send monitor. 120514 17:10:44 [Note] WSREP: gcomm: terminating thread 120514 17:10:44 [Note] WSREP: gcomm: joining thread 120514 17:10:44 [Note] WSREP: gcomm: closing backend 120514 17:10:44 [Note] WSREP: view((empty)) 120514 17:10:44 [Note] WSREP: gcomm: closed 120514 17:10:44 [Note] WSREP: Received self-leave message. 120514 17:10:44 [Note] WSREP: Flow-control interval: [0, 0] 120514 17:10:44 [Note] WSREP: Received SELF-LEAVE. Closing connection. 120514 17:10:44 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 123492) 120514 17:10:44 [Note] WSREP: RECV thread exiting 0: Success 120514 17:10:44 [Note] WSREP: recv_thread() joined. 120514 17:10:44 [Note] WSREP: Closing slave action queue. 120514 17:10:55 [ERROR] WSREP: got error while reading ist stream: 1 120514 17:10:55 [ERROR] WSREP: IST didn't contain all write sets, expected last: 123252 last received: 123176 120514 17:10:55 [ERROR] WSREP: receiving IST failed, node restart required: IST receiver reported error: 71 (Protocol error) at galera/src/ist.cpp:recv():470 120514 17:10:55 [Note] WSREP: Closing send monitor... 120514 17:10:55 [Note] WSREP: Closed send monitor. 120514 17:10:55 [Note] WSREP: /opt/mysql-galera/bin/mysqld: Terminated. 120514 17:10:56 mysqld_safe Number of processes running now: 0 120514 17:10:56 mysqld_safe mysqld restarted 120514 17:10:56 [Note] WSREP: Read nil XID from storage engines, skipping position init 120514 17:10:56 [Note] WSREP: wsrep_load(): loading provider library '/opt/mysql-galera/lib/plugin/libgalera_smm.so' 120514 17:10:56 [Note] WSREP: wsrep_load(): Galera 2.1dev(rXXXX) by Codership Oy loaded succesfully. 120514 17:10:56 [Note] WSREP: Found saved state: 6cd33b89-9dc2-11e1-0800-c938ffc57dd8:-1 120514 17:10:56 [Note] WSREP: Reusing existing '/opt/mysql-galera/data//galera.cache'. 120514 17:10:56 [Note] WSREP: Passing config to GCS: base_host = 192.168.0.5; gcache.dir = /opt/mysql-galera/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /opt/mysql-galera/data//galera.cache; gcache.page_size = 128M; gcache.size = 128M; gcs.fc_debug = 0; gcs.fc_factor = 0.5; gcs.fc_limit = 16; gcs.fc_master_slave = NO; gcs.max_packet_size = 64500; gcs.max_throttle = 0.25; gcs.recv_q_hard_limit = 2147483647; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = NO; replicator.causal_read_timeout = PT30S; replicator.commit_order = 3 120514 17:10:56 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 120514 17:10:56 [Note] WSREP: wsrep_sst_grab() 120514 17:10:56 [Note] WSREP: Start replication 120514 17:10:56 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 120514 17:10:56 [Note] WSREP: protonet asio version 0 120514 17:10:56 [Note] WSREP: backend: asio 120514 17:10:56 [Note] WSREP: GMCast version 0 120514 17:10:56 [Note] WSREP: (01348527-9dd7-11e1-0800-b1d995c7e1e5, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 120514 17:10:56 [Note] WSREP: (01348527-9dd7-11e1-0800-b1d995c7e1e5, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 120514 17:10:56 [Note] WSREP: EVS version 0 120514 17:10:56 [Note] WSREP: PC version 0 120514 17:10:56 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '192.168.0.1:4567' 120514 17:10:59 [Warning] WSREP: no nodes coming from prim view, prim not possible 120514 17:10:59 [Note] WSREP: view(view_id(NON_PRIM,01348527-9dd7-11e1-0800-b1d995c7e1e5,1) memb { 01348527-9dd7-11e1-0800-b1d995c7e1e5, } joined { } left { } partitioned { }) 120514 17:10:59 [Warning] WSREP: last inactive check more than PT1.5S ago, skipping check 120514 17:11:29 [Note] WSREP: view((empty)) 120514 17:11:29 [ERROR] WSREP: failed to open gcomm backend connection: 110: failed to reach primary view: 110 (Connection timed out) at gcomm/src/pc.cpp:connect():148 120514 17:11:29 [ERROR] WSREP: gcs/src/gcs_core.c:gcs_core_open():195: Failed to open backend connection: -110 (Connection timed out) 120514 17:11:29 [ERROR] WSREP: gcs/src/gcs.c:gcs_open():1290: Failed to open channel 'my_wsrep_cluster' at 'gcomm://192.168.0.1:4567': -110 (Connection timed out) 120514 17:11:29 [ERROR] WSREP: gcs connect failed: Connection timed out 120514 17:11:29 [ERROR] WSREP: wsrep::connect() failed: 6 120514 17:11:29 [ERROR] Aborting 120514 17:11:29 [Note] WSREP: Service disconnected. 120514 17:11:30 [Note] WSREP: Some threads may fail to exit. 120514 17:11:30 [Note] /opt/mysql-galera/bin/mysqld: Shutdown complete 120514 17:11:30 mysqld_safe mysqld from pid file /opt/mysql-galera/data/mysqld.pid ended