Not the answer you need?
Register and ask your own question!

Crash Cluster

HankHank EntrantCurrent User Role Beginner
Hello everybody!

I am newbie with Percona Cluster. Ive created a environment to test the product with mysql.

The version is: 5.6.15-56 Percona XtraDB Cluster (GPL), Release 25.5, Revision 759, wsrep_25.5.r4061

I am trying to understand why the cluster crash.

I have the log file like this:


WSREP_SST: [INFO] Evaluating socat -u TCP-LISTEN:4444,reuseaddr stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20140821 14:33:19.193)
2014-08-30 16:20:22 1369 [Note] WSREP: 1.0 (mysrvdb3): State transfer to 0.0 (mysrvdb4) complete.
2014-08-30 16:20:22 1369 [Note] WSREP: Member 1 (mysrvdb3) synced with group.
WSREP_SST: [INFO] Preparing the backup at /var/lib/mysql/ (20140821 16:20:22.698)
WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --apply-log $rebuildcmd ${DATA} &>${DATA}/innobackup.prepare.log (20140821 16:20:22.702)
WSREP_SST: [INFO] Total time on joiner: 0 seconds (20140821 16:20:27.927)
WSREP_SST: [INFO] Removing the sst_in_progress file (20140821 16:20:27.931)
2014-08-30 16:20:27 1369 [Note] WSREP: SST complete, seqno: 675157
2014-08-30 16:20:27 1369 [Warning] Using unique option prefix myisam-recover instead of myisam-recover-options is deprecated and will be removed in a future release. Please use the full name instead.
2014-08-30 16:20:27 1369 [Note] Plugin 'FEDERATED' is disabled.
2014-08-30 16:20:27 1369 [Note] InnoDB: The InnoDB memory heap is disabled
2014-08-30 16:20:27 1369 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-08-30 16:20:27 1369 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-08-30 16:20:27 1369 [Note] InnoDB: Using Linux native AIO
2014-08-30 16:20:27 1369 [Note] InnoDB: Using CPU crc32 instructions
2014-08-30 16:20:27 1369 [Note] InnoDB: Initializing buffer pool, size = 100.0G
2014-08-30 16:20:34 1369 [Note] InnoDB: Completed initialization of buffer pool
2014-08-30 16:20:34 1369 [Note] InnoDB: Highest supported file format is Barracuda.
2014-08-30 16:20:38 1369 [Note] InnoDB: 128 rollback segment(s) are active.
2014-08-30 16:20:38 1369 [Note] InnoDB: Waiting for purge to start
2014-08-30 16:20:38 1369 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.15-rel63.0 started; log sequence number 11911833459
2014-08-30 16:20:38 1369 [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: 6cfa22d9-2959-11e4-9c05-0000c9e31460.
2014-08-30 16:20:38 1369 [Note] RSA private key file not found: /var/lib/mysql//private_key.pem. Some authentication plugins will not work.
2014-08-30 16:20:38 1369 [Note] RSA public key file not found: /var/lib/mysql//public_key.pem. Some authentication plugins will not work.
2014-08-30 16:20:38 1369 [Note] Server hostname (bind-address): '*'; port: 3306
2014-08-30 16:20:38 1369 [Note] IPv6 is available.
2014-08-30 16:20:38 1369 [Note] - '::' resolves to '::';
2014-08-30 16:20:58 1369 [Note] Server socket created on IP: '::'.
2014-08-30 16:20:58 1369 [Note] Event Scheduler: Loaded 0 events
2014-08-30 16:20:58 1369 [Note] WSREP: Signalling provider to continue.
2014-08-30 16:20:58 1369 [Note] WSREP: inited wsrep sidno 1
2014-08-30 16:20:58 1369 [Note] WSREP: SST received: 034e6fd9-e5c6-11e3-aea0-3728475880e0:675157
2014-08-30 16:20:58 1369 [Note] WSREP: 0.0 (mysrvdb4): State transfer from 1.0 (mysrvdb3) complete.
2014-08-30 16:20:58 1369 [Note] WSREP: Shifting JOINER -> JOINED (TO: 675163)
2014-08-30 16:20:58 1369 [Note] WSREP: Member 0 (mysrvdb4) synced with group.
2014-08-30 16:20:58 1369 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 675163)
2014-08-30 16:20:58 1369 [Note] /usr/sbin/mysqld: ready for connections.
Version: '5.6.15-56' socket: '/var/lib/mysql/mysql.sock' port: 3306 Percona XtraDB Cluster (GPL), Release 25.5, Revision 759, wsrep_25.5.r4061
2014-08-30 16:20:58 1369 [Note] WSREP: Synchronized with group, ready for connections
2014-08-30 16:20:58 1369 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2014-08-30 14:36:46 1369 [ERROR] Slave SQL: Error 'Can't create database 'perfdb01'; database exists' on query. Default database: ''. Query: 'create database perfdb01', Error_code: 1007
2014-08-30 14:36:46 1369 [Warning] WSREP: RBR event 1 Query apply warning: 1, 675189
2014-08-30 14:36:46 1369 [Warning] WSREP: Ignoring error for TO isolated action: source: 91b56b0f-143a-11e4-862c-b2d5386b5d98 version: 3 local: 0 state: APPLYING flags: 65 conn_id: 216060 trx_id: -1 seqnos (l: 36, g: 675189, s: 675188, d: 675188, ts: 3211173698510532)
2014-08-30 14:43:03 1369 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000000 of size 134217728 bytes
2014-08-30 14:43:07 1369 [Note] WSREP: Deleted page /var/lib/mysql/gcache.page.000000
17:43:10 UTC - mysqld got signal 11 ;
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.
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.
Please help us make Percona XtraDB Cluster better by reporting any
bugs at https://bugs.launchpad.net/percona-xtradb-cluster
key_buffer_size=33554432
read_buffer_size=131072
max_used_connections=15
max_threads=216
thread_count=13
connection_count=13
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 506007 K bytes of memory
Hope that's ok; if not, decrease some variables in the equation.

Thread pointer: 0x7f4828000990
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...
stack_bottom = 7f48641c4d98 thread_stack 0x40000
/usr/sbin/mysqld(my_print_stacktrace+0x35)[0x902645]
/usr/sbin/mysqld(handle_fatal_signal+0x4c4)[0x6801e4]
/lib64/libpthread.so.0[0x314be0f710]
/usr/lib64/libgalera_smm.so(_ZN6galera13Certification10do_test_v3EPNS_9TrxHandleEb+0x1b1)[0x7f48482f1be1]
/usr/lib64/libgalera_smm.so(_ZN6galera13Certification7do_testEPNS_9TrxHandleEb+0x37f)[0x7f48482f601f]
/usr/lib64/libgalera_smm.so(_ZN6galera13Certification4testEPNS_9TrxHandleEb+0x28)[0x7f48482f61f8]
/usr/lib64/libgalera_smm.so(_ZN6galera13Certification10append_trxEPNS_9TrxHandleE+0x8b)[0x7f48482f629b]
/usr/lib64/libgalera_smm.so(_ZN6galera13ReplicatorSMM4certEPNS_9TrxHandleE+0x8b)[0x7f484831cedb]
/usr/lib64/libgalera_smm.so(_ZN6galera13ReplicatorSMM11process_trxEPvPNS_9TrxHandleE+0x31)[0x7f4848324a91]
/usr/lib64/libgalera_smm.so(_ZN6galera15GcsActionSource8dispatchEPvRK10gcs_actionRb+0x2c9)[0x7f48482ffa89]
/usr/lib64/libgalera_smm.so(_ZN6galera15GcsActionSource7processEPvRb+0x63)[0x7f48482fff63]
/usr/lib64/libgalera_smm.so(_ZN6galera13ReplicatorSMM10async_recvEPv+0x93)[0x7f484831edb3]
/usr/lib64/libgalera_smm.so(galera_recv+0x23)[0x7f4848333aa3]
/usr/sbin/mysqld[0x5be11f]
/usr/sbin/mysqld(start_wsrep_THD+0x3fe)[0x5ae42e]
/lib64/libpthread.so.0[0x314be079d1]
/lib64/libc.so.6(clone+0x6d)[0x314b6e8b5d]

Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0): Connection ID (thread ID): 1
Status: NOT_KILLED

My opinion is: the developer tried to import a database and this crashs the cluster. But I'm not sure about this conclusion.

Please, somebody can help me!!!

Thank very much!

Comments

  • niljoshiniljoshi MySQL Sage Inactive User Role Beginner
    Hi Hank,

    Yes, as per the error, database is already there and some one/process tried to create it again.

    2014-08-30 14:36:46 1369 [ERROR] Slave SQL: Error 'Can't create database 'perfdb01'; database exists' on query. Default database: ''. Query: 'create database perfdb01', Error_code: 1007

    Can you tell us when you got this error? while starting any node? or cluster was running and you got this error suddenly ?
  • HankHank Entrant Current User Role Beginner
    Hi niljoshi,

    The Cluster was working fine. So, Its possible WHEN the developer imported database to Mysql the Cluster crash on the two nodes. But I'm not sure. I did tests in another server and doesn't return error.
    The log of the another node of this cluster is simply the signal 11 without any error before. But after I can see erro im libgalera:
    I think is galera error. The log on this another node is:

    UTC - mysqld got signal 11 ;
    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.
    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.
    Please help us make Percona XtraDB Cluster better by reporting any
    bugs at https://bugs.launchpad.net/percona-xtradb-cluster

    key_buffer_size=33554432
    read_buffer_size=131072
    max_used_connections=28
    max_threads=1002
    thread_count=24
    connection_count=24
    It is possible that mysqld could use up to
    key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 2228127 K bytes of memory
    Hope that's ok; if not, decrease some variables in the equation.

    Thread pointer: 0xac2c3b30
    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...
    stack_bottom = 7ff3824e1d38 thread_stack 0x40000
    /usr/sbin/mysqld(my_print_stacktrace+0x35)[0x902645]
    /usr/sbin/mysqld(handle_fatal_signal+0x4c4)[0x6801e4]
    /lib64/libpthread.so.0(+0xf710)[0x7ff3cc0ef710]
    /usr/lib64/libgalera_smm.so(_ZN6galera13Certification10do_test_v3EPNS_9TrxHandleEb+0x1b1)[0x7ff3c92eebe1]
    /usr/lib64/libgalera_smm.so(_ZN6galera13Certification7do_testEPNS_9TrxHandleEb+0x37f)[0x7ff3c92f301f]
    /usr/lib64/libgalera_smm.so(_ZN6galera13Certification4testEPNS_9TrxHandleEb+0x28)[0x7ff3c92f31f8]
    /usr/lib64/libgalera_smm.so(_ZN6galera13Certification10append_trxEPNS_9TrxHandleE+0x8b)[0x7ff3c92f329b]
    /usr/lib64/libgalera_smm.so(_ZN6galera13ReplicatorSMM4certEPNS_9TrxHandleE+0x8b)[0x7ff3c9319edb]
    /usr/lib64/libgalera_smm.so(_ZN6galera13ReplicatorSMM10pre_commitEPNS_9TrxHandleEP14wsrep_trx_meta+0x59)[0x7ff3c931c939]
    /usr/lib64/libgalera_smm.so(galera_pre_commit+0x148)[0x7ff3c9330758]
    /usr/sbin/mysqld(_Z22wsrep_run_wsrep_commitP3THDP10handlertonb+0x688)[0x7ba448]
    /usr/sbin/mysqld(_Z14ha_prepare_lowP3THDb+0x8c)[0x5c3c8c]
    /usr/sbin/mysqld(_Z15ha_commit_transP3THDbb+0x27c)[0x5c55cc]
    /usr/sbin/mysqld(_Z17trans_commit_stmtP3THD+0x35)[0x79fd75]
    /usr/sbin/mysqld(_Z21mysql_execute_commandP3THD+0x90c)[0x701f5c]
    /usr/sbin/mysqld(_Z11mysql_parseP3THDPcjP12Parser_state+0x608)[0x707908]
    /usr/sbin/mysqld[0x707a31]
    /usr/sbin/mysqld(_Z16dispatch_command19enum_server_commandP3THDPcj+0x1ad4)[0x709cb4]
    /usr/sbin/mysqld(_Z10do_commandP3THD+0x1e3)[0x70b083]
    /usr/sbin/mysqld(_Z24do_handle_one_connectionP3THD+0x17f)[0x6d492f]
    /usr/sbin/mysqld(handle_one_connection+0x47)[0x6d4b07]
    /usr/sbin/mysqld(pfs_spawn_thread+0x12a)[0xb3c0da]
    /lib64/libpthread.so.0(+0x79d1)[0x7ff3cc0e79d1]
    /lib64/libc.so.6(clone+0x6d)[0x7ff3caf21b5d]

    I would like to understand if this import (create database with error) have caused the cluster crash or there is a bug.
    And what to do to avoid this kind of problem.

    Thank you very much!
  • HankHank Entrant Current User Role Beginner
    Hello,

    I got another error:

    2014-09-03 14:06:34 8535 [ERROR] Slave SQL: Error 'Duplicate column name 'DESC_DS'' on query. Default database: 'PROD1'. Query: 'alter table T1 add DESC_DS DATE AFTER ID', Error_code: 1060
    .
    .
    .
    2014-09-03 17:20:12 8535 [Warning] WSREP: BF applier failed to open_and_lock_tables: 1615, fatal: 0 wsrep = (exec_mode: 1 conflict_state: 5 seqno: 734544)
    2014-09-03 17:20:12 8535 [Warning] WSREP: RBR event 3 Update_rows apply warning: 1615, 734544
    2014-09-03 17:20:12 8535 [Warning] WSREP: failed to replay trx: source: b0c6f7b8-2964-11e4-8301-df11d0cd2f98 version: 3 local: 1 state: REPLAYING flags: 1 conn_id: 125870 trx_id: 17397413 seqnos (l: 737378, g: 734544, s: 734542, d: 734543, ts: 4413658020837793)
    2014-09-03 17:20:12 8535 [Warning] WSREP: Failed to apply trx 734544 4 times
    2014-09-03 17:20:12 8535 [ERROR] WSREP: trx_replay failed for: 6, query: void
    2014-09-03 17:20:12 8535 [ERROR] Aborting


    Please, anybody can help me?

    Thank you!
  • przemekprzemek Percona Support Engineer Percona Staff Role
    Can you provide more details:
    Do you use any asynchronous slaves in this PXC cluster?
    How are those imports look like - example CREATE TABLE query, is the data loaded with LOAD DATA?,etc.
    Do you have any MyISAM tables?
    Include your my.cnf
    Did you do any DDLs in RSU mode?
Sign In or Register to comment.

MySQL, InnoDB, MariaDB and MongoDB are trademarks of their respective owners.
Copyright ©2005 - 2020 Percona LLC. All rights reserved.