Attachment 951294 Details for Bug 1157405 – maria db log server1

maria db log server1

server1-maridb.txt (text/plain), 61.26 KB, created by Asaf Hirshberg on 2014-10-28 07:06:45 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Asaf Hirshberg

Created: 2014-10-28 07:06:45 UTC

Size: 61.26 KB

patch

obsolete

>141028  8:22:55 [Note] WSREP: Read nil XID from storage engines, skipping position init
>141028  8:22:55 [Note] WSREP: wsrep_load(): loading provider library 'none'
>141028  8:22:56 [Note] WSREP: Service disconnected.
>141028  8:22:57 [Note] WSREP: Some threads may fail to exit.
>141028  8:22:58 [Note] WSREP: Read nil XID from storage engines, skipping position init
>141028  8:22:58 [Note] WSREP: wsrep_load(): loading provider library 'none'
>141028  8:22:58 [Note] WSREP: Service disconnected.
>141028  8:22:59 [Note] WSREP: Some threads may fail to exit.
>141028 08:22:59 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:22:59 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.95T5Sk' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:22:59 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:22:59 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:22:59 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:23:02 mysqld_safe WSREP: Recovered position 00000000-0000-0000-0000-000000000000:-1
>141028  8:23:02 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:23:02 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:23:02 [Note] WSREP: wsrep_start_position var submitted: '00000000-0000-0000-0000-000000000000:-1'
>141028  8:23:02 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028  8:23:02 [Note] WSREP: Read nil XID from storage engines, skipping position init
>141028  8:23:02 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
>141028  8:23:02 [Note] WSREP: wsrep_load(): Galera 3.5(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
>141028  8:23:02 [Note] WSREP: CRC-32C: using hardware acceleration.
>141028  8:23:02 [Warning] WSREP: Could not open saved state file for reading: /var/lib/mysql//grastate.dat
>141028  8:23:02 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1
>141028  8:23:02 [Note] WSREP: Passing config to GCS: base_host = 192.168.0.16; base_port = 4567; cert.log_conflicts = no; debug = no; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 1; 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 = /var/lib/mysql/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//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 = false; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = P30S; pc.weight = 1; protone
>141028  8:23:02 [Note] WSREP: Service thread queue flushed.
>141028  8:23:02 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
>141028  8:23:02 [Note] WSREP: wsrep_sst_grab()
>141028  8:23:02 [Note] WSREP: Start replication
>141028  8:23:02 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
>141028  8:23:02 [Note] WSREP: protonet asio version 0
>141028  8:23:02 [Note] WSREP: Using CRC-32C (optimized) for message checksums.
>141028  8:23:02 [Note] WSREP: backend: asio
>141028  8:23:02 [Note] WSREP: GMCast version 0
>141028  8:23:02 [Note] WSREP: (df118f2a-5e6a-11e4-b9bf-0665221608f4, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
>141028  8:23:02 [Note] WSREP: (df118f2a-5e6a-11e4-b9bf-0665221608f4, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
>141028  8:23:02 [Note] WSREP: EVS version 0
>141028  8:23:02 [Note] WSREP: PC version 0
>141028  8:23:02 [Note] WSREP: gcomm: connecting to group 'galera_cluster', peer '192.168.0.14:,192.168.0.15:,192.168.0.16:'
>141028  8:23:03 [Warning] WSREP: (df118f2a-5e6a-11e4-b9bf-0665221608f4, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' points to own listening address, blacklisting
>141028  8:23:03 [Note] WSREP: (df118f2a-5e6a-11e4-b9bf-0665221608f4, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid df118f2a-5e6a-11e4-b9bf-0665221608f4 is blacklisted, skipping
>141028  8:23:03 [Note] WSREP: (df118f2a-5e6a-11e4-b9bf-0665221608f4, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid df118f2a-5e6a-11e4-b9bf-0665221608f4 is blacklisted, skipping
>141028  8:23:03 [Note] WSREP: (df118f2a-5e6a-11e4-b9bf-0665221608f4, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid df118f2a-5e6a-11e4-b9bf-0665221608f4 is blacklisted, skipping
>141028  8:23:03 [Note] WSREP: (df118f2a-5e6a-11e4-b9bf-0665221608f4, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid df118f2a-5e6a-11e4-b9bf-0665221608f4 is blacklisted, skipping
>141028  8:23:03 [Note] WSREP: declaring c7db8a98-5e6a-11e4-a3e9-b70144fbcd49 stable
>141028  8:23:03 [Note] WSREP: declaring dd82d0d6-5e6a-11e4-84af-fa188258785f stable
>141028  8:23:03 [Note] WSREP: Node c7db8a98-5e6a-11e4-a3e9-b70144fbcd49 state prim
>141028  8:23:03 [Note] WSREP: view(view_id(PRIM,c7db8a98-5e6a-11e4-a3e9-b70144fbcd49,3) memb {
>	c7db8a98-5e6a-11e4-a3e9-b70144fbcd49,0
>	dd82d0d6-5e6a-11e4-84af-fa188258785f,0
>	df118f2a-5e6a-11e4-b9bf-0665221608f4,0
>} joined {
>} left {
>} partitioned {
>})
>141028  8:23:03 [Note] WSREP: gcomm: connected
>141028  8:23:03 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
>141028  8:23:03 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
>141028  8:23:03 [Note] WSREP: Opened channel 'galera_cluster'
>141028  8:23:04 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 2, memb_num = 3
>141028  8:23:04 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
>141028  8:23:04 [Note] WSREP: Waiting for SST to complete.
>141028  8:23:04 [Note] WSREP: STATE EXCHANGE: sent state msg: df80cce0-5e6a-11e4-a0fb-fac0e3eb0813
>141028  8:23:04 [Note] WSREP: STATE EXCHANGE: got state msg: df80cce0-5e6a-11e4-a0fb-fac0e3eb0813 from 0 (mac848f69fbc4c3.example.com)
>141028  8:23:04 [Note] WSREP: STATE EXCHANGE: got state msg: df80cce0-5e6a-11e4-a0fb-fac0e3eb0813 from 1 (mac848f69fbc49f.example.com)
>141028  8:23:04 [Note] WSREP: STATE EXCHANGE: got state msg: df80cce0-5e6a-11e4-a0fb-fac0e3eb0813 from 2 (macf04da2732fb1.example.com)
>141028  8:23:04 [Note] WSREP: Quorum results:
>	version    = 3,
>	component  = PRIMARY,
>	conf_id    = 2,
>	members    = 1/3 (joined/total),
>	act_id     = 20,
>	last_appl. = -1,
>	protocols  = 0/5/2 (gcs/repl/appl),
>	group UUID = c7dbf19d-5e6a-11e4-bc09-23e8ab105a19
>141028  8:23:04 [Note] WSREP: Flow-control interval: [28, 28]
>141028  8:23:04 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 20)
>141028  8:23:04 [Note] WSREP: State transfer required: 
>	Group state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20
>	Local state: 00000000-0000-0000-0000-000000000000:-1
>141028  8:23:04 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20, view# 3: Primary, number of nodes: 3, my index: 2, protocol version 2
>141028  8:23:04 [Warning] WSREP: Gap in state sequence. Need state transfer.
>141028  8:23:04 [Note] WSREP: Member 1.0 (mac848f69fbc49f.example.com) requested state transfer from '*any*'. Selected 0.0 (mac848f69fbc4c3.example.com)(SYNCED) as donor.
>141028  8:23:05 [Note] WSREP: 0.0 (mac848f69fbc4c3.example.com): State transfer to 1.0 (mac848f69fbc49f.example.com) complete.
>141028  8:23:05 [Note] WSREP: Member 0.0 (mac848f69fbc4c3.example.com) synced with group.
>141028  8:23:06 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '192.168.0.16' --auth 'sst_user:sst_pass' --datadir '/var/lib/mysql/' --defaults-file '/etc/my.cnf' --parent '17306''
>141028  8:23:06 [Note] WSREP: Prepared SST request: rsync|192.168.0.16:4444/rsync_sst
>141028  8:23:06 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:23:06 [Note] WSREP: REPL Protocols: 5 (3, 1)
>141028  8:23:06 [Note] WSREP: Service thread queue flushed.
>141028  8:23:06 [Note] WSREP: Assign initial position for certification: 20, protocol version: 3
>141028  8:23:06 [Note] WSREP: Service thread queue flushed.
>141028  8:23:06 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (c7dbf19d-5e6a-11e4-bc09-23e8ab105a19): 1 (Operation not permitted)
>	 at galera/src/replicator_str.cpp:prepare_for_IST():447. IST will be unavailable.
>141028  8:23:06 [Note] WSREP: Member 2.0 (macf04da2732fb1.example.com) requested state transfer from '*any*'. Selected 0.0 (mac848f69fbc4c3.example.com)(SYNCED) as donor.
>141028  8:23:06 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 20)
>141028  8:23:06 [Note] WSREP: Requesting state transfer: success, donor: 0
>141028  8:23:07 [Note] WSREP: 0.0 (mac848f69fbc4c3.example.com): State transfer to 2.0 (macf04da2732fb1.example.com) complete.
>141028  8:23:07 [Note] WSREP: Member 0.0 (mac848f69fbc4c3.example.com) synced with group.
>141028  8:23:07 [Note] WSREP: 1.0 (mac848f69fbc49f.example.com): State transfer from 0.0 (mac848f69fbc4c3.example.com) complete.
>141028  8:23:07 [Note] WSREP: Member 1.0 (mac848f69fbc49f.example.com) synced with group.
>WSREP_SST: [INFO] Joiner cleanup. (20141028 08:23:08.152)
>WSREP_SST: [INFO] Joiner cleanup done. (20141028 08:23:08.658)
>141028  8:23:08 [Note] WSREP: SST complete, seqno: 20
>141028  8:23:08 InnoDB: The InnoDB memory heap is disabled
>141028  8:23:08 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:23:08 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:23:08 InnoDB: Using Linux native AIO
>141028  8:23:08 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:23:08 InnoDB: Completed initialization of buffer pool
>141028  8:23:08 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!
>141028  8:23:08  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...
>141028  8:23:08  InnoDB: Waiting for the background threads to start
>141028  8:23:09 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 1598293
>141028  8:23:09 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:23:09 [Note] Server socket created on IP: '192.168.0.16'.
>141028  8:23:09 [Note] Event Scheduler: Loaded 0 events
>141028  8:23:09 [Note] WSREP: Signalling provider to continue.
>141028  8:23:09 [Note] WSREP: SST received: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20
>141028  8:23:09 [Note] /usr/libexec/mysqld: ready for connections.
>Version: '5.5.37-MariaDB-wsrep'  socket: '/var/lib/mysql/mysql.sock'  port: 3306  MariaDB Server, wsrep_25.10.r3980
>141028  8:23:09 [Note] WSREP: 2.0 (macf04da2732fb1.example.com): State transfer from 0.0 (mac848f69fbc4c3.example.com) complete.
>141028  8:23:09 [Note] WSREP: Shifting JOINER -> JOINED (TO: 20)
>141028  8:23:09 [Note] WSREP: Member 2.0 (macf04da2732fb1.example.com) synced with group.
>141028  8:23:09 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 20)
>141028  8:23:09 [Note] WSREP: Synchronized with group, ready for connections
>141028  8:23:09 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:23:12 [Note] /usr/libexec/mysqld: Normal shutdown
>
>141028  8:23:12 [Note] WSREP: Stop replication
>141028  8:23:12 [Note] WSREP: Closing send monitor...
>141028  8:23:12 [Note] WSREP: Closed send monitor.
>141028  8:23:12 [Note] WSREP: gcomm: terminating thread
>141028  8:23:12 [Note] WSREP: gcomm: joining thread
>141028  8:23:12 [Note] WSREP: gcomm: closing backend
>141028  8:23:12 [Note] WSREP: view(view_id(NON_PRIM,c7db8a98-5e6a-11e4-a3e9-b70144fbcd49,3) memb {
>	df118f2a-5e6a-11e4-b9bf-0665221608f4,0
>} joined {
>} left {
>} partitioned {
>	c7db8a98-5e6a-11e4-a3e9-b70144fbcd49,0
>	dd82d0d6-5e6a-11e4-84af-fa188258785f,0
>})
>141028  8:23:12 [Note] WSREP: view((empty))
>141028  8:23:12 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
>141028  8:23:12 [Note] WSREP: gcomm: closed
>141028  8:23:12 [Note] WSREP: Flow-control interval: [16, 16]
>141028  8:23:12 [Note] WSREP: Received NON-PRIMARY.
>141028  8:23:12 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 20)
>141028  8:23:12 [Note] WSREP: Received self-leave message.
>141028  8:23:12 [Note] WSREP: Flow-control interval: [0, 0]
>141028  8:23:12 [Note] WSREP: Received SELF-LEAVE. Closing connection.
>141028  8:23:12 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 20)
>141028  8:23:12 [Note] WSREP: RECV thread exiting 0: Success
>141028  8:23:12 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 2
>141028  8:23:12 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:23:12 [Note] WSREP: recv_thread() joined.
>141028  8:23:12 [Note] WSREP: Closing replication queue.
>141028  8:23:12 [Note] WSREP: Closing slave action queue.
>141028  8:23:12 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 2
>141028  8:23:12 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:23:12 [Note] WSREP: applier thread exiting (code:0)
>141028  8:23:14 [Note] WSREP: rollbacker thread exiting
>141028  8:23:14 [Note] Event Scheduler: Purging the queue. 0 events
>141028  8:23:14 [Note] WSREP: dtor state: CLOSED
>141028  8:23:14 [Note] WSREP: apply mon: entered 0
>141028  8:23:14 [Note] WSREP: apply mon: entered 0
>141028  8:23:14 [Note] WSREP: mon: entered 5 oooe fraction 0 oool fraction 0.2
>141028  8:23:14 [Note] WSREP: cert index usage at exit 0
>141028  8:23:14 [Note] WSREP: cert trx map usage at exit 0
>141028  8:23:14 [Note] WSREP: deps set usage at exit 0
>141028  8:23:14 [Note] WSREP: avg deps dist 0
>141028  8:23:14 [Note] WSREP: avg cert interval 0
>141028  8:23:14 [Note] WSREP: cert index size 0
>141028  8:23:14 [Note] WSREP: Service thread queue flushed.
>141028  8:23:14 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
>141028  8:23:14 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 20)
>141028  8:23:14 [Note] WSREP: Flushing memory map to disk...
>141028  8:23:14  InnoDB: Starting shutdown...
>141028  8:23:15  InnoDB: Shutdown completed; log sequence number 1598293
>141028  8:23:15 [Note] /usr/libexec/mysqld: Shutdown complete
>
>141028 08:23:15 mysqld_safe mysqld from pid file /var/run/mariadb/mariadb.pid ended
>141028 08:23:26 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:23:26 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.SHIaJ5' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:23:26 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:23:26 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:23:26 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:23:28 mysqld_safe WSREP: Recovered position c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:0
>141028  8:23:28 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:23:28 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:23:28 [Note] WSREP: wsrep_start_position var submitted: 'c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:0'
>141028  8:23:28 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028  8:23:28 [Note] WSREP: Read nil XID from storage engines, skipping position init
>141028  8:23:28 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
>141028  8:23:28 [Note] WSREP: wsrep_load(): Galera 3.5(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
>141028  8:23:28 [Note] WSREP: CRC-32C: using hardware acceleration.
>141028  8:23:28 [Note] WSREP: Found saved state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20
>141028  8:23:28 [Note] WSREP: Passing config to GCS: base_host = 192.168.0.16; base_port = 4567; cert.log_conflicts = no; debug = no; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 1; 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 = /var/lib/mysql/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//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 = false; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = P30S; pc.weight = 1; protone
>141028  8:23:28 [Note] WSREP: Service thread queue flushed.
>141028  8:23:28 [Note] WSREP: Assign initial position for certification: 20, protocol version: -1
>141028  8:23:28 [Note] WSREP: wsrep_sst_grab()
>141028  8:23:28 [Note] WSREP: Start replication
>141028  8:23:28 [Note] WSREP: Setting initial position to c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20
>141028  8:23:28 [Note] WSREP: protonet asio version 0
>141028  8:23:28 [Note] WSREP: Using CRC-32C (optimized) for message checksums.
>141028  8:23:28 [Note] WSREP: backend: asio
>141028  8:23:28 [Note] WSREP: GMCast version 0
>141028  8:23:28 [Note] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
>141028  8:23:28 [Note] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
>141028  8:23:28 [Note] WSREP: EVS version 0
>141028  8:23:28 [Note] WSREP: PC version 0
>141028  8:23:28 [Note] WSREP: gcomm: connecting to group 'galera_cluster', peer '192.168.0.14:,192.168.0.15:,192.168.0.16:'
>141028  8:23:28 [Warning] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' points to own listening address, blacklisting
>141028  8:23:28 [Note] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61 is blacklisted, skipping
>141028  8:23:29 [Note] WSREP: declaring ee65bc6e-5e6a-11e4-8fa0-3347dd340481 stable
>141028  8:23:29 [Warning] WSREP: no nodes coming from prim view, prim not possible
>141028  8:23:29 [Note] WSREP: view(view_id(NON_PRIM,ee65bc6e-5e6a-11e4-8fa0-3347dd340481,1) memb {
>	ee65bc6e-5e6a-11e4-8fa0-3347dd340481,0
>	ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61,0
>} joined {
>} left {
>} partitioned {
>})
>141028  8:23:30 [Note] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61 is blacklisted, skipping
>141028  8:23:30 [Note] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61 is blacklisted, skipping
>141028  8:23:30 [Note] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61 is blacklisted, skipping
>141028  8:23:30 [Note] WSREP: (ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61 is blacklisted, skipping
>141028  8:23:30 [Note] WSREP: declaring ee65bc6e-5e6a-11e4-8fa0-3347dd340481 stable
>141028  8:23:30 [Note] WSREP: declaring eefa3c57-5e6a-11e4-9626-bb2f50f3b9d8 stable
>141028  8:23:30 [Note] WSREP: Node eefa3c57-5e6a-11e4-9626-bb2f50f3b9d8 state prim
>141028  8:23:30 [Note] WSREP: view(view_id(PRIM,ee65bc6e-5e6a-11e4-8fa0-3347dd340481,2) memb {
>	ee65bc6e-5e6a-11e4-8fa0-3347dd340481,0
>	ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61,0
>	eefa3c57-5e6a-11e4-9626-bb2f50f3b9d8,0
>} joined {
>} left {
>} partitioned {
>})
>141028  8:23:30 [Note] WSREP: gcomm: connected
>141028  8:23:30 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
>141028  8:23:30 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
>141028  8:23:30 [Note] WSREP: Opened channel 'galera_cluster'
>141028  8:23:30 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 1, memb_num = 2
>141028  8:23:30 [Note] WSREP: Waiting for SST to complete.
>141028  8:23:30 [Note] WSREP: Flow-control interval: [23, 23]
>141028  8:23:30 [Note] WSREP: Received NON-PRIMARY.
>141028  8:23:30 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 3
>141028  8:23:30 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
>141028  8:23:30 [Note] WSREP: STATE EXCHANGE: sent state msg: ef97567c-5e6a-11e4-8c56-726e8083d620
>141028  8:23:30 [Note] WSREP: STATE EXCHANGE: got state msg: ef97567c-5e6a-11e4-8c56-726e8083d620 from 0 (mac848f69fbc49f.example.com)
>141028  8:23:30 [Note] WSREP: STATE EXCHANGE: got state msg: ef97567c-5e6a-11e4-8c56-726e8083d620 from 2 (mac848f69fbc4c3.example.com)
>141028  8:23:30 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20, view# -1: non-Primary, number of nodes: 2, my index: 1, protocol version -1
>141028  8:23:30 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:23:30 [Note] WSREP: STATE EXCHANGE: got state msg: ef97567c-5e6a-11e4-8c56-726e8083d620 from 1 (macf04da2732fb1.example.com)
>141028  8:23:30 [Note] WSREP: Quorum results:
>	version    = 3,
>	component  = PRIMARY,
>	conf_id    = 1,
>	members    = 3/3 (joined/total),
>	act_id     = 20,
>	last_appl. = -1,
>	protocols  = 0/5/2 (gcs/repl/appl),
>	group UUID = c7dbf19d-5e6a-11e4-bc09-23e8ab105a19
>141028  8:23:30 [Note] WSREP: Flow-control interval: [28, 28]
>141028  8:23:30 [Note] WSREP: Restored state OPEN -> JOINED (20)
>141028  8:23:30 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:20, view# 2: Primary, number of nodes: 3, my index: 1, protocol version 2
>141028  8:23:30 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:23:30 [Note] WSREP: REPL Protocols: 5 (3, 1)
>141028  8:23:30 [Note] WSREP: Service thread queue flushed.
>141028  8:23:30 [Note] WSREP: Member 0.0 (mac848f69fbc49f.example.com) synced with group.
>141028  8:23:30 [Note] WSREP: Member 1.0 (macf04da2732fb1.example.com) synced with group.
>141028  8:23:30 [Note] WSREP: Assign initial position for certification: 20, protocol version: 3
>141028  8:23:30 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 20)
>141028  8:23:30 [Note] WSREP: Service thread queue flushed.
>141028  8:23:30 [Note] WSREP: Synchronized with group, ready for connections
>141028  8:23:30 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:23:30 [Note] WSREP: SST complete, seqno: 0
>141028  8:23:30 InnoDB: The InnoDB memory heap is disabled
>141028  8:23:30 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:23:30 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:23:30 InnoDB: Using Linux native AIO
>141028  8:23:30 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:23:30 InnoDB: Completed initialization of buffer pool
>141028  8:23:30 InnoDB: highest supported file format is Barracuda.
>141028  8:23:31  InnoDB: Waiting for the background threads to start
>141028  8:23:32 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 1598293
>141028  8:23:32 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:23:32 [Note] Server socket created on IP: '192.168.0.16'.
>141028  8:23:32 [Note] Event Scheduler: Loaded 0 events
>141028  8:23:32 [Note] /usr/libexec/mysqld: ready for connections.
>Version: '5.5.37-MariaDB-wsrep'  socket: '/var/lib/mysql/mysql.sock'  port: 3306  MariaDB Server, wsrep_25.10.r3980
>141028  8:43:58 [Note] /usr/libexec/mysqld: Normal shutdown
>
>141028  8:43:58 [Note] WSREP: Stop replication
>141028  8:43:58 [Note] WSREP: Closing send monitor...
>141028  8:43:58 [Note] WSREP: Closed send monitor.
>141028  8:43:58 [Note] WSREP: gcomm: terminating thread
>141028  8:43:58 [Note] WSREP: gcomm: joining thread
>141028  8:43:58 [Note] WSREP: gcomm: closing backend
>141028  8:43:58 [Note] WSREP: view(view_id(NON_PRIM,ee65bc6e-5e6a-11e4-8fa0-3347dd340481,2) memb {
>	ee818bcb-5e6a-11e4-9b08-4ac7a9f88e61,0
>} joined {
>} left {
>} partitioned {
>	ee65bc6e-5e6a-11e4-8fa0-3347dd340481,0
>	eefa3c57-5e6a-11e4-9626-bb2f50f3b9d8,0
>})
>141028  8:43:58 [Note] WSREP: view((empty))
>141028  8:43:58 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
>141028  8:43:58 [Note] WSREP: gcomm: closed
>141028  8:43:58 [Note] WSREP: Flow-control interval: [16, 16]
>141028  8:43:58 [Note] WSREP: Received NON-PRIMARY.
>141028  8:43:58 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 2575)
>141028  8:43:58 [Note] WSREP: Received self-leave message.
>141028  8:43:58 [Note] WSREP: Flow-control interval: [0, 0]
>141028  8:43:58 [Note] WSREP: Received SELF-LEAVE. Closing connection.
>141028  8:43:58 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 2575)
>141028  8:43:58 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2575, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 2
>141028  8:43:58 [Note] WSREP: RECV thread exiting 0: Success
>141028  8:43:58 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:43:58 [Note] WSREP: recv_thread() joined.
>141028  8:43:58 [Note] WSREP: Closing replication queue.
>141028  8:43:58 [Note] WSREP: Closing slave action queue.
>141028  8:43:58 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2575, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 2
>141028  8:43:58 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:43:58 [Note] WSREP: applier thread exiting (code:0)
>141028  8:44:00 [Note] WSREP: rollbacker thread exiting
>141028  8:44:00 [Note] Event Scheduler: Purging the queue. 0 events
>141028  8:44:00 [Note] WSREP: dtor state: CLOSED
>141028  8:44:00 [Note] WSREP: mon: entered 2555 oooe fraction 0 oool fraction 0
>141028  8:44:00 [Note] WSREP: mon: entered 2555 oooe fraction 0 oool fraction 0
>141028  8:44:00 [Note] WSREP: mon: entered 2580 oooe fraction 0 oool fraction 0
>141028  8:44:00 [Note] WSREP: cert index usage at exit 0
>141028  8:44:00 [Note] WSREP: cert trx map usage at exit 57
>141028  8:44:00 [Note] WSREP: deps set usage at exit 0
>141028  8:44:00 [Note] WSREP: avg deps dist 9.26928
>141028  8:44:00 [Note] WSREP: avg cert interval 0.053229
>141028  8:44:00 [Note] WSREP: cert index size 34
>141028  8:44:00 [Note] WSREP: Service thread queue flushed.
>141028  8:44:00 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
>141028  8:44:00 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 2575)
>141028  8:44:00 [Note] WSREP: Flushing memory map to disk...
>141028  8:44:00  InnoDB: Starting shutdown...
>141028  8:44:01  InnoDB: Shutdown completed; log sequence number 6359871
>141028  8:44:01 [Note] /usr/libexec/mysqld: Shutdown complete
>
>141028 08:44:01 mysqld_safe mysqld from pid file /var/run/mariadb/mariadb.pid ended
>141028 08:44:14 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:44:14 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.Wd6z6m' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:44:14 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:44:14 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:44:14 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:44:16 mysqld_safe WSREP: Recovered position c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2575
>141028  8:44:16 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:44:16 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:44:16 [Note] WSREP: wsrep_start_position var submitted: 'c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2575'
>141028  8:44:16 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028  8:44:16 [Note] WSREP: Read nil XID from storage engines, skipping position init
>141028  8:44:16 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
>141028  8:44:16 [Note] WSREP: wsrep_load(): Galera 3.5(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
>141028  8:44:16 [Note] WSREP: CRC-32C: using hardware acceleration.
>141028  8:44:16 [Note] WSREP: Found saved state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2575
>141028  8:44:16 [Note] WSREP: Passing config to GCS: base_host = 192.168.0.16; base_port = 4567; cert.log_conflicts = no; debug = no; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 1; 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 = /var/lib/mysql/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//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 = false; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = P30S; pc.weight = 1; protone
>141028  8:44:16 [Note] WSREP: Service thread queue flushed.
>141028  8:44:16 [Note] WSREP: Assign initial position for certification: 2575, protocol version: -1
>141028  8:44:16 [Note] WSREP: wsrep_sst_grab()
>141028  8:44:16 [Note] WSREP: Start replication
>141028  8:44:16 [Note] WSREP: Setting initial position to c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2575
>141028  8:44:16 [Note] WSREP: protonet asio version 0
>141028  8:44:16 [Note] WSREP: Using CRC-32C (optimized) for message checksums.
>141028  8:44:16 [Note] WSREP: backend: asio
>141028  8:44:16 [Note] WSREP: GMCast version 0
>141028  8:44:16 [Note] WSREP: (d64527e3-5e6d-11e4-9cc6-c2058307b159, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
>141028  8:44:16 [Note] WSREP: (d64527e3-5e6d-11e4-9cc6-c2058307b159, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
>141028  8:44:16 [Note] WSREP: EVS version 0
>141028  8:44:16 [Note] WSREP: PC version 0
>141028  8:44:16 [Note] WSREP: gcomm: connecting to group 'galera_cluster', peer ''
>141028  8:44:16 [Note] WSREP: Node d64527e3-5e6d-11e4-9cc6-c2058307b159 state prim
>141028  8:44:16 [Note] WSREP: view(view_id(PRIM,d64527e3-5e6d-11e4-9cc6-c2058307b159,1) memb {
>	d64527e3-5e6d-11e4-9cc6-c2058307b159,0
>} joined {
>} left {
>} partitioned {
>})
>141028  8:44:16 [Note] WSREP: gcomm: connected
>141028  8:44:16 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
>141028  8:44:16 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
>141028  8:44:16 [Note] WSREP: Opened channel 'galera_cluster'
>141028  8:44:16 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
>141028  8:44:16 [Note] WSREP: Waiting for SST to complete.
>141028  8:44:16 [Note] WSREP: STATE_EXCHANGE: sent state UUID: d6458b23-5e6d-11e4-9355-4f0563cc259c
>141028  8:44:16 [Note] WSREP: STATE EXCHANGE: sent state msg: d6458b23-5e6d-11e4-9355-4f0563cc259c
>141028  8:44:16 [Note] WSREP: STATE EXCHANGE: got state msg: d6458b23-5e6d-11e4-9355-4f0563cc259c from 0 (macf04da2732fb1.example.com)
>141028  8:44:16 [Note] WSREP: Quorum results:
>	version    = 3,
>	component  = PRIMARY,
>	conf_id    = 0,
>	members    = 1/1 (joined/total),
>	act_id     = 2575,
>	last_appl. = -1,
>	protocols  = 0/5/2 (gcs/repl/appl),
>	group UUID = c7dbf19d-5e6a-11e4-bc09-23e8ab105a19
>141028  8:44:16 [Note] WSREP: Flow-control interval: [16, 16]
>141028  8:44:16 [Note] WSREP: Restored state OPEN -> JOINED (2575)
>141028  8:44:16 [Note] WSREP: Member 0.0 (macf04da2732fb1.example.com) synced with group.
>141028  8:44:16 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 2575)
>141028  8:44:16 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2575, view# 1: Primary, number of nodes: 1, my index: 0, protocol version 2
>141028  8:44:16 [Note] WSREP: SST complete, seqno: 2575
>141028  8:44:16 InnoDB: The InnoDB memory heap is disabled
>141028  8:44:16 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:44:16 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:44:16 InnoDB: Using Linux native AIO
>141028  8:44:16 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:44:16 InnoDB: Completed initialization of buffer pool
>141028  8:44:16 InnoDB: highest supported file format is Barracuda.
>141028  8:44:16  InnoDB: Waiting for the background threads to start
>141028  8:44:17 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 6359871
>141028  8:44:17 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:44:17 [Note] Server socket created on IP: '192.168.0.16'.
>141028  8:44:17 [Note] Event Scheduler: Loaded 0 events
>141028  8:44:17 [Note] /usr/libexec/mysqld: ready for connections.
>Version: '5.5.37-MariaDB-wsrep'  socket: '/var/lib/mysql/mysql.sock'  port: 3306  MariaDB Server, wsrep_25.10.r3980
>141028  8:44:17 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:44:17 [Note] WSREP: REPL Protocols: 5 (3, 1)
>141028  8:44:17 [Note] WSREP: Service thread queue flushed.
>141028  8:44:17 [Note] WSREP: Assign initial position for certification: 2575, protocol version: 3
>141028  8:44:17 [Note] WSREP: Service thread queue flushed.
>141028  8:44:17 [Note] WSREP: Synchronized with group, ready for connections
>141028  8:44:17 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:46:09 [Note] /usr/libexec/mysqld: Normal shutdown
>
>141028  8:46:09 [Note] WSREP: Stop replication
>141028  8:46:09 [Note] WSREP: Closing send monitor...
>141028  8:46:09 [Note] WSREP: Closed send monitor.
>141028  8:46:09 [Note] WSREP: gcomm: terminating thread
>141028  8:46:09 [Note] WSREP: gcomm: joining thread
>141028  8:46:09 [Note] WSREP: gcomm: closing backend
>141028  8:46:09 [Note] WSREP: view((empty))
>141028  8:46:09 [Note] WSREP: Received self-leave message.
>141028  8:46:09 [Note] WSREP: gcomm: closed
>141028  8:46:09 [Note] WSREP: Flow-control interval: [0, 0]
>141028  8:46:09 [Note] WSREP: Received SELF-LEAVE. Closing connection.
>141028  8:46:09 [Note] WSREP: Shifting SYNCED -> CLOSED (TO: 2585)
>141028  8:46:09 [Note] WSREP: RECV thread exiting 0: Success
>141028  8:46:09 [Note] WSREP: recv_thread() joined.
>141028  8:46:09 [Note] WSREP: Closing replication queue.
>141028  8:46:09 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2585, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 2
>141028  8:46:09 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:46:09 [Note] WSREP: Closing slave action queue.
>141028  8:46:09 [Note] WSREP: applier thread exiting (code:0)
>141028  8:46:11 [Note] WSREP: rollbacker thread exiting
>141028  8:46:11 [Note] Event Scheduler: Purging the queue. 0 events
>141028  8:46:11 [Note] WSREP: dtor state: CLOSED
>141028  8:46:11 [Note] WSREP: mon: entered 10 oooe fraction 0 oool fraction 0
>141028  8:46:11 [Note] WSREP: mon: entered 10 oooe fraction 0 oool fraction 0
>141028  8:46:11 [Note] WSREP: mon: entered 13 oooe fraction 0 oool fraction 0
>141028  8:46:11 [Note] WSREP: cert index usage at exit 0
>141028  8:46:11 [Note] WSREP: cert trx map usage at exit 10
>141028  8:46:11 [Note] WSREP: deps set usage at exit 0
>141028  8:46:11 [Note] WSREP: avg deps dist 1
>141028  8:46:11 [Note] WSREP: avg cert interval 0
>141028  8:46:11 [Note] WSREP: cert index size 5
>141028  8:46:11 [Note] WSREP: Service thread queue flushed.
>141028  8:46:11 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
>141028  8:46:11 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 2585)
>141028  8:46:11 [Note] WSREP: Flushing memory map to disk...
>141028  8:46:11  InnoDB: Starting shutdown...
>141028  8:46:12  InnoDB: Shutdown completed; log sequence number 6367896
>141028  8:46:12 [Note] /usr/libexec/mysqld: Shutdown complete
>
>141028 08:46:12 mysqld_safe mysqld from pid file /var/run/mariadb/mariadb.pid ended
>141028 08:46:12 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:46:12 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.jYQ0Mc' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:46:12 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:46:12 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:46:12 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:46:14 mysqld_safe WSREP: Recovered position c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2585
>141028  8:46:14 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:46:14 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:46:14 [Note] WSREP: wsrep_start_position var submitted: 'c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2585'
>141028  8:46:14 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028  8:46:14 [Note] WSREP: Read nil XID from storage engines, skipping position init
>141028  8:46:14 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
>141028  8:46:14 [Note] WSREP: wsrep_load(): Galera 3.5(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
>141028  8:46:14 [Note] WSREP: CRC-32C: using hardware acceleration.
>141028  8:46:14 [Note] WSREP: Found saved state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2585
>141028  8:46:14 [Note] WSREP: Passing config to GCS: base_host = 192.168.0.16; base_port = 4567; cert.log_conflicts = no; debug = no; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 1; 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 = /var/lib/mysql/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//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 = false; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = P30S; pc.weight = 1; protone
>141028  8:46:14 [Note] WSREP: Service thread queue flushed.
>141028  8:46:14 [Note] WSREP: Assign initial position for certification: 2585, protocol version: -1
>141028  8:46:14 [Note] WSREP: wsrep_sst_grab()
>141028  8:46:14 [Note] WSREP: Start replication
>141028  8:46:14 [Note] WSREP: Setting initial position to c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2585
>141028  8:46:14 [Note] WSREP: protonet asio version 0
>141028  8:46:14 [Note] WSREP: Using CRC-32C (optimized) for message checksums.
>141028  8:46:14 [Note] WSREP: backend: asio
>141028  8:46:14 [Note] WSREP: GMCast version 0
>141028  8:46:14 [Note] WSREP: (1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
>141028  8:46:14 [Note] WSREP: (1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
>141028  8:46:14 [Note] WSREP: EVS version 0
>141028  8:46:14 [Note] WSREP: PC version 0
>141028  8:46:14 [Note] WSREP: gcomm: connecting to group 'galera_cluster', peer '192.168.0.14:,192.168.0.15:,192.168.0.16:'
>141028  8:46:14 [Warning] WSREP: (1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' points to own listening address, blacklisting
>141028  8:46:14 [Note] WSREP: (1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid 1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210 is blacklisted, skipping
>141028  8:46:14 [Note] WSREP: (1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid 1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210 is blacklisted, skipping
>141028  8:46:14 [Note] WSREP: (1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210, 'tcp://0.0.0.0:4567') address 'tcp://192.168.0.16:4567' pointing to uuid 1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210 is blacklisted, skipping
>141028  8:46:15 [Note] WSREP: declaring ee65bc6e-5e6a-11e4-8fa0-3347dd340481 stable
>141028  8:46:15 [Note] WSREP: declaring eefa3c57-5e6a-11e4-9626-bb2f50f3b9d8 stable
>141028  8:46:15 [Note] WSREP: Node ee65bc6e-5e6a-11e4-8fa0-3347dd340481 state prim
>141028  8:46:15 [Note] WSREP: view(view_id(PRIM,1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210,4) memb {
>	1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210,0
>	ee65bc6e-5e6a-11e4-8fa0-3347dd340481,0
>	eefa3c57-5e6a-11e4-9626-bb2f50f3b9d8,0
>} joined {
>} left {
>} partitioned {
>})
>141028  8:46:15 [Note] WSREP: gcomm: connected
>141028  8:46:15 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
>141028  8:46:15 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
>141028  8:46:15 [Note] WSREP: Opened channel 'galera_cluster'
>141028  8:46:15 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 3
>141028  8:46:15 [Note] WSREP: Waiting for SST to complete.
>141028  8:46:15 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 1d5731f3-5e6e-11e4-a71f-637ab3bb3d2e
>141028  8:46:15 [Note] WSREP: STATE EXCHANGE: sent state msg: 1d5731f3-5e6e-11e4-a71f-637ab3bb3d2e
>141028  8:46:15 [Note] WSREP: STATE EXCHANGE: got state msg: 1d5731f3-5e6e-11e4-a71f-637ab3bb3d2e from 0 (macf04da2732fb1.example.com)
>141028  8:46:15 [Note] WSREP: STATE EXCHANGE: got state msg: 1d5731f3-5e6e-11e4-a71f-637ab3bb3d2e from 1 (mac848f69fbc49f.example.com)
>141028  8:46:15 [Note] WSREP: STATE EXCHANGE: got state msg: 1d5731f3-5e6e-11e4-a71f-637ab3bb3d2e from 2 (mac848f69fbc4c3.example.com)
>141028  8:46:15 [Note] WSREP: Quorum results:
>	version    = 3,
>	component  = PRIMARY,
>	conf_id    = 3,
>	members    = 2/3 (joined/total),
>	act_id     = 2780,
>	last_appl. = -1,
>	protocols  = 0/5/2 (gcs/repl/appl),
>	group UUID = c7dbf19d-5e6a-11e4-bc09-23e8ab105a19
>141028  8:46:15 [Note] WSREP: Flow-control interval: [28, 28]
>141028  8:46:15 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 2780)
>141028  8:46:15 [Note] WSREP: State transfer required: 
>	Group state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2780
>	Local state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2585
>141028  8:46:15 [Note] WSREP: New cluster view: global state: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2780, view# 4: Primary, number of nodes: 3, my index: 0, protocol version 2
>141028  8:46:15 [Warning] WSREP: Gap in state sequence. Need state transfer.
>141028  8:46:17 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '192.168.0.16' --auth 'sst_user:sst_pass' --datadir '/var/lib/mysql/' --defaults-file '/etc/my.cnf' --parent '75765''
>141028  8:46:18 [Note] WSREP: Prepared SST request: rsync|192.168.0.16:4444/rsync_sst
>141028  8:46:18 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
>141028  8:46:18 [Note] WSREP: REPL Protocols: 5 (3, 1)
>141028  8:46:18 [Note] WSREP: Service thread queue flushed.
>141028  8:46:18 [Note] WSREP: Assign initial position for certification: 2780, protocol version: 3
>141028  8:46:18 [Note] WSREP: Service thread queue flushed.
>141028  8:46:18 [Note] WSREP: IST receiver using ssl
>141028  8:46:18 [Note] WSREP: Prepared IST receiver, listening at: ssl://192.168.0.16:4568
>141028  8:46:18 [Note] WSREP: Member 0.0 (macf04da2732fb1.example.com) requested state transfer from '*any*'. Selected 1.0 (mac848f69fbc49f.example.com)(SYNCED) as donor.
>141028  8:46:18 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 2791)
>141028  8:46:18 [Note] WSREP: Requesting state transfer: success, donor: 1
>WSREP_SST: [INFO] Joiner cleanup. (20141028 08:46:19.112)
>WSREP_SST: [INFO] Joiner cleanup done. (20141028 08:46:19.619)
>141028  8:46:19 [Note] WSREP: SST complete, seqno: 2585
>141028  8:46:19 InnoDB: The InnoDB memory heap is disabled
>141028  8:46:19 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:46:19 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:46:19 InnoDB: Using Linux native AIO
>141028  8:46:19 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:46:19 InnoDB: Completed initialization of buffer pool
>141028  8:46:19 InnoDB: highest supported file format is Barracuda.
>141028  8:46:19  InnoDB: Waiting for the background threads to start
>141028  8:46:20 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 6367896
>141028  8:46:20 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:46:20 [Note] Server socket created on IP: '192.168.0.16'.
>141028  8:46:20 [Note] /usr/libexec/mysqld: Normal shutdown
>
>141028  8:46:20 [Note] WSREP: Stop replication
>141028  8:46:20 [Note] WSREP: Closing send monitor...
>141028  8:46:20 [Note] WSREP: Closed send monitor.
>141028  8:46:20 [Note] WSREP: gcomm: terminating thread
>141028  8:46:20 [Note] WSREP: gcomm: joining thread
>141028  8:46:20 [Note] WSREP: gcomm: closing backend
>141028  8:46:20 [Note] WSREP: view(view_id(NON_PRIM,1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210,4) memb {
>	1cbe3ffc-5e6e-11e4-9de6-3fe4d5739210,0
>} joined {
>} left {
>} partitioned {
>	ee65bc6e-5e6a-11e4-8fa0-3347dd340481,0
>	eefa3c57-5e6a-11e4-9626-bb2f50f3b9d8,0
>})
>141028  8:46:20 [Note] WSREP: view((empty))
>141028  8:46:20 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
>141028  8:46:20 [Note] WSREP: gcomm: closed
>141028  8:46:20 [Note] WSREP: Flow-control interval: [16, 16]
>141028  8:46:20 [Note] WSREP: Received NON-PRIMARY.
>141028  8:46:20 [Note] WSREP: Shifting JOINER -> OPEN (TO: 2799)
>141028  8:46:20 [Note] WSREP: Received self-leave message.
>141028  8:46:20 [Note] WSREP: Flow-control interval: [0, 0]
>141028  8:46:20 [Note] WSREP: Received SELF-LEAVE. Closing connection.
>141028  8:46:20 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 2799)
>141028  8:46:20 [Note] WSREP: RECV thread exiting 0: Success
>141028  8:46:20 [Note] WSREP: recv_thread() joined.
>141028  8:46:20 [Note] WSREP: Closing replication queue.
>141028  8:46:20 [Note] WSREP: Closing slave action queue.
>141028  8:46:20 [Note] Event Scheduler: Loaded 0 events
>141028  8:46:20 [Note] WSREP: Signalling provider to continue.
>141028  8:46:20 [Note] WSREP: SST received: c7dbf19d-5e6a-11e4-bc09-23e8ab105a19:2585
>141028  8:46:20 [ERROR] WSREP: Trying to launch slave threads before creating connection at 'gcomm://192.168.0.14,192.168.0.15,192.168.0.16'
>mysqld: /builddir/build/BUILD/mariadb-5.5.37/sql/wsrep_thd.cc:307: void wsrep_create_appliers(long int): Assertion `0' failed.
>141028  8:46:20 [ERROR] mysqld got signal 6 ;
>This could be because you hit a bug. It is also possible that this binary
>or one of the libraries it was linked against is corrupt, improperly built,
>or misconfigured. This error can also be caused by malfunctioning hardware.
>
>To report this bug, see http://kb.askmonty.org/en/reporting-bugs
>
>We will try our best to scrape up some info that will hopefully help
>diagnose the problem, but since we have already crashed, 
>something is definitely wrong and this may fail.
>
>Server version: 5.5.37-MariaDB-wsrep
>key_buffer_size=16777216
>read_buffer_size=131072
>max_used_connections=0
>max_threads=1026
>thread_count=2
>It is possible that mysqld could use up to 
>key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 2267738 K  bytes of memory
>Hope that's ok; if not, decrease some variables in the equation.
>
>Thread pointer: 0x0x0
>Attempting backtrace. You can use the following information to find out
>where mysqld died. If you see no messages after this, something went
>terribly wrong...
>141028  8:46:20 [Note] WSREP: Receiving IST: 195 writesets, seqnos 2585-2780
>stack_bottom = 0x0 thread_stack 0x30000
>/usr/libexec/mysqld(my_print_stacktrace+0x2e)[0x7f0054eddcae]
>/usr/libexec/mysqld(handle_fatal_signal+0x505)[0x7f0054afc105]
>/lib64/libpthread.so.0(+0xf130)[0x7f0054211130]
>/lib64/libc.so.6(gsignal+0x39)[0x7f00529c95c9]
>/lib64/libc.so.6(abort+0x148)[0x7f00529cacd8]
>/lib64/libc.so.6(+0x2e536)[0x7f00529c2536]
>/lib64/libc.so.6(+0x2e5e2)[0x7f00529c25e2]
>/usr/libexec/mysqld(+0x475ee6)[0x7f0054ab6ee6]
>/usr/libexec/mysqld(_Z11mysqld_mainiPPc+0x12c2)[0x7f005493c922]
>/lib64/libc.so.6(__libc_start_main+0xf5)[0x7f00529b5af5]
>/usr/libexec/mysqld(+0x2ef9bd)[0x7f00549309bd]
>The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
>information that should help you find out what is causing the crash.
>141028 08:46:20 mysqld_safe Number of processes running now: 0
>141028 08:46:20 mysqld_safe WSREP: not restarting wsrep node automatically
>141028 08:46:20 mysqld_safe mysqld from pid file /var/run/mariadb/mariadb.pid ended
>141028 08:47:41 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:47:41 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.VcbAQ8' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:47:41 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:47:41 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:47:41 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:47:44 mysqld_safe WSREP: Failed to recover position: 
>'141028  8:47:41 InnoDB: The InnoDB memory heap is disabled
>141028  8:47:41 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:47:41 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:47:41 InnoDB: Using Linux native AIO
>141028  8:47:41 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:47:41 InnoDB: Completed initialization of buffer pool
>141028  8:47:41 InnoDB: highest supported file format is Barracuda.
>InnoDB: Log scan progressed past the checkpoint lsn 6367896
>141028  8:47:41  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: Doing recovery: scanned up to log sequence number 6368225
>InnoDB: Transaction 2400 was in the XA prepared state.
>InnoDB: 1 transaction(s) which must be rolled back or cleaned up
>InnoDB: in total 0 row operations to undo
>InnoDB: Trx id counter is 2600
>141028  8:47:41  InnoDB: Starting an apply batch of log records to the database...
>InnoDB: Progress in percents: 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 
>InnoDB: Apply batch completed
>InnoDB: Starting in background the rollback of uncommitted transactions
>141028  8:47:42  InnoDB: Rollback of non-prepared transactions completed
>141028  8:47:42  InnoDB: Waiting for the background threads to start
>141028  8:47:43 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 6368225
>141028  8:47:43 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:47:43  InnoDB: Starting recovery for XA transactions...
>141028  8:47:43  InnoDB: Transaction 2400 in prepared state after recovery
>141028  8:47:43  InnoDB: Transaction contains changes to 1 rows
>141028  8:47:43  InnoDB: 1 transactions in prepared state after recovery
>141028  8:47:43 [Note] Found 1 prepared transaction(s) in InnoDB
>141028  8:47:43 [ERROR] Found 1 prepared transactions! It means that mysqld was not shut down properly last time and critical recovery information (last binlog or tc.log file) was manually deleted after a crash. You have to start mysqld with --tc-heuristic-recover switch to commit or rollback pending transactions.
>141028  8:47:43 [ERROR] Aborting
>
>141028  8:47:43  InnoDB: Starting shutdown...
>141028  8:47:44  InnoDB: Shutdown completed; log sequence number 6368225
>141028  8:47:44 [Note] /usr/libexec/mysqld: Shutdown complete'
>141028 08:49:21 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:49:21 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.PqpWFH' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:49:21 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:49:21 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:49:21 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:49:23 mysqld_safe WSREP: Failed to recover position: 
>'141028  8:49:21 InnoDB: The InnoDB memory heap is disabled
>141028  8:49:21 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:49:21 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:49:21 InnoDB: Using Linux native AIO
>141028  8:49:21 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:49:21 InnoDB: Completed initialization of buffer pool
>141028  8:49:21 InnoDB: highest supported file format is Barracuda.
>InnoDB: Transaction 2400 was in the XA prepared state.
>InnoDB: 1 transaction(s) which must be rolled back or cleaned up
>InnoDB: in total 0 row operations to undo
>InnoDB: Trx id counter is 2600
>InnoDB: Starting in background the rollback of uncommitted transactions
>141028  8:49:22  InnoDB: Rollback of non-prepared transactions completed
>141028  8:49:22  InnoDB: Waiting for the background threads to start
>141028  8:49:23 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 6368225
>141028  8:49:23 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:49:23  InnoDB: Starting recovery for XA transactions...
>141028  8:49:23  InnoDB: Transaction 2400 in prepared state after recovery
>141028  8:49:23  InnoDB: Transaction contains changes to 1 rows
>141028  8:49:23  InnoDB: 1 transactions in prepared state after recovery
>141028  8:49:23 [Note] Found 1 prepared transaction(s) in InnoDB
>141028  8:49:23 [ERROR] Found 1 prepared transactions! It means that mysqld was not shut down properly last time and critical recovery information (last binlog or tc.log file) was manually deleted after a crash. You have to start mysqld with --tc-heuristic-recover switch to commit or rollback pending transactions.
>141028  8:49:23 [ERROR] Aborting
>
>141028  8:49:23  InnoDB: Starting shutdown...
>141028  8:49:23  InnoDB: Shutdown completed; log sequence number 6368225
>141028  8:49:23 [Note] /usr/libexec/mysqld: Shutdown complete'
>141028 08:54:38 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:54:38 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.yavWqI' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:54:38 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:54:38 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:54:38 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:54:45 mysqld_safe WSREP: Failed to recover position: 
>'141028  8:54:38 InnoDB: The InnoDB memory heap is disabled
>141028  8:54:38 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:54:38 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:54:38 InnoDB: Using Linux native AIO
>141028  8:54:38 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:54:38 InnoDB: Completed initialization of buffer pool
>141028  8:54:38 InnoDB: highest supported file format is Barracuda.
>InnoDB: Transaction 2400 was in the XA prepared state.
>InnoDB: 1 transaction(s) which must be rolled back or cleaned up
>InnoDB: in total 0 row operations to undo
>InnoDB: Trx id counter is 2600
>InnoDB: Starting in background the rollback of uncommitted transactions
>141028  8:54:40  InnoDB: Rollback of non-prepared transactions completed
>141028  8:54:40  InnoDB: Waiting for the background threads to start
>141028  8:54:41 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 6368225
>141028  8:54:41 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:54:41  InnoDB: Starting recovery for XA transactions...
>141028  8:54:41  InnoDB: Transaction 2400 in prepared state after recovery
>141028  8:54:41  InnoDB: Transaction contains changes to 1 rows
>141028  8:54:41  InnoDB: 1 transactions in prepared state after recovery
>141028  8:54:41 [Note] Found 1 prepared transaction(s) in InnoDB
>141028  8:54:41 [ERROR] Found 1 prepared transactions! It means that mysqld was not shut down properly last time and critical recovery information (last binlog or tc.log file) was manually deleted after a crash. You have to start mysqld with --tc-heuristic-recover switch to commit or rollback pending transactions.
>141028  8:54:41 [ERROR] Aborting
>
>141028  8:54:41  InnoDB: Starting shutdown...
>141028  8:54:44  InnoDB: Shutdown completed; log sequence number 6368225
>141028  8:54:45 [Note] /usr/libexec/mysqld: Shutdown complete'
>141028 08:55:26 mysqld_safe Starting mysqld daemon with databases from /var/lib/mysql
>141028 08:55:26 mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/mysql/wsrep_recovery.URGuvF' --pid-file='/var/lib/mysql/macf04da2732fb1.example.com-recover.pid'
>141028  8:55:26 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:55:26 [Warning] option 'open_files_limit': unsigned value 18446744073709551615 adjusted to 4294967295
>141028  8:55:26 [Warning] Could not increase number of max_open_files to more than 1024 (request: 1835)
>/usr/libexec/mysqld: Query cache is disabled (resize or similar command in progress); repeat this command later
>141028 08:55:28 mysqld_safe WSREP: Failed to recover position: 
>'141028  8:55:26 InnoDB: The InnoDB memory heap is disabled
>141028  8:55:26 InnoDB: Mutexes and rw_locks use GCC atomic builtins
>141028  8:55:26 InnoDB: Compressed tables use zlib 1.2.7
>141028  8:55:26 InnoDB: Using Linux native AIO
>141028  8:55:26 InnoDB: Initializing buffer pool, size = 128.0M
>141028  8:55:26 InnoDB: Completed initialization of buffer pool
>141028  8:55:26 InnoDB: highest supported file format is Barracuda.
>InnoDB: Transaction 2400 was in the XA prepared state.
>InnoDB: 1 transaction(s) which must be rolled back or cleaned up
>InnoDB: in total 0 row operations to undo
>InnoDB: Trx id counter is 2600
>InnoDB: Starting in background the rollback of uncommitted transactions
>141028  8:55:26  InnoDB: Rollback of non-prepared transactions completed
>141028  8:55:26  InnoDB: Waiting for the background threads to start
>141028  8:55:27 Percona XtraDB (http://www.percona.com) 5.5.37-MariaDB-34.0 started; log sequence number 6368225
>141028  8:55:27 [Note] Plugin 'FEEDBACK' is disabled.
>141028  8:55:27  InnoDB: Starting recovery for XA transactions...
>141028  8:55:27  InnoDB: Transaction 2400 in prepared state after recovery
>141028  8:55:27  InnoDB: Transaction contains changes to 1 rows
>141028  8:55:27  InnoDB: 1 transactions in prepared state after recovery
>141028  8:55:27 [Note] Found 1 prepared transaction(s) in InnoDB
>141028  8:55:27 [ERROR] Found 1 prepared transactions! It means that mysqld was not shut down properly last time and critical recovery information (last binlog or tc.log file) was manually deleted after a crash. You have to start mysqld with --tc-heuristic-recover switch to commit or rollback pending transactions.
>141028  8:55:27 [ERROR] Aborting
>
>141028  8:55:27  InnoDB: Starting shutdown...
>141028  8:55:28  InnoDB: Shutdown completed; log sequence number 6368225
>141028  8:55:28 [Note] /usr/libexec/mysqld: Shutdown complete'

Actions: View

Attachments on bug 1157405: 950903 | 950917 | 951291 | 951292 | 951293 | 951294 | 951295 | 951296