Comment 8 for bug 1234421

Revision history for this message
Raghavendra D Prabhu (raghavendra-prabhu) wrote :

Ran with --mysqld=--wsrep-debug=1 --mysqld=--wsrep_provider_options='debug=1'

From following:

2013-10-09 00:54:58 43148 [Note] WSREP: TO BEGIN: 638, 2
2013-10-09 00:54:58 43148 [Note] WSREP: MDL conflict
request: (15 seqno 638 wsrep (2, 1, 0) cmd 3 9 DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc`)
granted: (12 seqno 639 wsrep (0, 2, 0) cmd 3 54 COMMIT)
2013-10-09 00:54:58 43148 [Note] WSREP: MDL ticket: type: shared read space: TABLE db: test name: table100_innodb_key_pk_parts_2_int_autoinc
2013-10-09 00:54:58 43148 [Note] WSREP: DROP caused BF abort
2013-10-09 00:54:58 43148 [Note] WSREP: MDL ticket: type: shared read space: TABLE db: test name: table100_innodb_key_pk_parts_2_int_autoinc
2013-10-09 00:54:58 43148 [Note] WSREP: wsrep_abort_thd, by: 139940104804096, victim: 139940105602816
2013-10-09 00:54:58 43148 [Note] WSREP: abort transaction: BF: DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc` victim: COMMIT
2013-10-09 00:54:58 43148 [Note] WSREP: cluster conflict due to high priority abort for threads:
2013-10-09 00:54:58 43148 [Note] WSREP: Winning thread:
   THD: 15, mode: total order, state: executing, conflict: no conflict, seqno: 638
   SQL: DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc`
2013-10-09 00:54:58 43148 [Note] WSREP: Victim thread:
   THD: 12, mode: local, state: committing, conflict: no conflict, seqno: 639
   SQL: COMMIT
2013-10-09 00:54:58 43148 [Note] WSREP: BF kill (1, seqno: 638), victim: (12) trx: 7691
2013-10-09 00:54:58 43148 [Note] WSREP: Aborting query: COMMIT
2013-10-09 00:54:58 43148 [Note] WSREP: kill trx QUERY_COMMITTING for 7691
2013-10-09 00:54:58 43148 [Note] [Debug] WSREP: galera/src/replicator_smm.cpp:abort_trx():672: aborting trx source: 34df666e-304f-11e3-b505-e799705b723b version: 3 local: 1 state: COMMITTING flags: 133 conn_id: 12 trx_id: 7691 seqnos (l: 661, g: 639, s: 636, d: 638, ts: 101562216784543) 0x7f46040b9f00
2013-10-09 00:54:58 43148 [Note] WSREP: MDL conflict
request: (15 seqno 638 wsrep (2, 1, 0) cmd 3 9 DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc`)
granted: (12 seqno 639 wsrep (0, 2, 1) cmd 3 54 COMMIT)
2013-10-09 00:54:58 43148 [Note] WSREP: MDL ticket: type: shared read space: TABLE db: test name: table100_innodb_key_pk_parts_2_int_autoinc
2013-10-09 00:54:58 43148 [Note] WSREP: DROP caused BF abort
2013-10-09 00:54:58 43148 [Note] WSREP: MDL ticket: type: shared read space: TABLE db: test name: table100_innodb_key_pk_parts_2_int_autoinc
2013-10-09 00:54:58 43148 [Note] WSREP: wsrep_abort_thd, by: 139940104804096, victim: 139940105602816
2013-10-09 00:54:59 43148 [Note] WSREP: abort transaction: BF: DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc` victim: COMMIT
2013-10-09 00:54:59 43148 [Note] WSREP: cluster conflict due to high priority abort for threads:
2013-10-09 00:54:59 43148 [Note] WSREP: Winning thread:
   THD: 15, mode: total order, state: executing, conflict: no conflict, seqno: 638
   SQL: DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc`
2013-10-09 00:54:59 43148 [Note] WSREP: Victim thread:
   THD: 12, mode: local, state: committing, conflict: must abort, seqno: 639
   SQL: COMMIT
2013-10-09 00:54:59 43148 [Note] WSREP: BF kill (1, seqno: 638), victim: (12) trx: 7691
2013-10-09 00:54:59 43148 [Note] WSREP: Aborting query: COMMIT
2013-10-09 00:54:59 43148 [Note] WSREP: victim 7691 in MUST ABORT state
2013-10-09 00:54:59 43148 [Note] WSREP: MDL victim killed at add_ticket
2013-10-09 00:54:59 43148 [Note] WSREP: TO BEGIN: 637, 0 : ALTER TABLE g RENAME `table1_innodb_key_pk_parts_2_int_autoinc`
2013-10-09 00:54:59 43148 [Note] WSREP: replaying increased: 1, thd: 12
2013-10-09 00:54:59 43148 [Note] WSREP: commit failed for reason: 4
2013-10-09 00:54:59 43148 [Note] WSREP: conflict state: 4
2013-10-09 00:54:59 43148 [Note] WSREP: replay trx: COMMIT 639
2013-10-09 00:54:59 43148 [Note] WSREP: TO END: 638, 2 : DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc`
2013-10-09 00:54:59 43148 [Note] [Debug] WSREP: galera/src/replicator_smm.cpp:to_isolation_end():1076: Done executing TO isolated action: source: 34df666e-304f-11e3-b505-e799705b723b version: 3 local: 1 state: APPLYING flags: 65 conn_id: 15 trx_id: -1 seqnos (l: 660, g: 638, s: 636, d: 637, ts: 101562176003850)
2013-10-09 00:54:59 43148 [Note] WSREP: TO END: 638
2013-10-09 00:54:59 43148 [Note] WSREP: TO BEGIN: 638, 0 : ALTER TABLE b RENAME `table100_innodb_key_pk_parts_2_int_autoinc`
2013-10-09 00:54:59 43148 [Note] WSREP: trx_replay successful for: 12 139940105602816
2013-10-09 00:54:59 43148 [Note] [Debug] WSREP: galera/src/replicator_smm.cpp:to_isolation_begin():1050: Executing TO isolated action: source: 34df666e-304f-11e3-b505-e799705b723b version: 3 local: 1 state: APPLYING flags: 65 conn_id: 17 trx_id: -1 seqnos (l: 662, g: 640, s: 637, d: 639, ts: 101562250805334)
mysqld: /media/Tintin/Work/code/percona-xtradb-cluster/pxc56/Percona-Server/sql/sql_error.cc:430: void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*): Assertion `! is_set()' failed.
2013-10-09 00:54:59 43148 [Note] WSREP: TO BEGIN: 640, 2
19:24:59 UTC - 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.
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 Server better by reporting any
bugs at http://bugs.percona.com/

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

Thread pointer: 0x2ec4bc0
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 = 7f465848bed8 thread_stack 0x40000
/pxc56/bin/mysqld(my_print_stacktrace+0x2c)[0x942f48]
/pxc56/bin/mysqld(handle_fatal_signal+0x304)[0x6d8da8]
/usr/lib/libpthread.so.0(+0xf870)[0x7f466df58870]
/usr/lib/libc.so.6(gsignal+0x39)[0x7f466c2e63d9]
/usr/lib/libc.so.6(abort+0x148)[0x7f466c2e77d8]
/usr/lib/libc.so.6(+0x2e506)[0x7f466c2df506]
/usr/lib/libc.so.6(+0x2e5b2)[0x7f466c2df5b2]
/pxc56/bin/mysqld(_ZN16Diagnostics_area13set_ok_statusEyyPKc+0x62)[0x73072c]
/pxc56/bin/mysqld(_Z5my_okP3THDyyPKc+0x35)[0x63bce8]
/pxc56/bin/mysqld(_Z24wsrep_replay_transactionP3THD+0x443)[0x63b590]
/pxc56/bin/mysqld[0x75baeb]
/pxc56/bin/mysqld(_Z16dispatch_command19enum_server_commandP3THDPcj+0xbab)[0x75c9cd]
/pxc56/bin/mysqld(_Z10do_commandP3THD+0x50c)[0x75df23]
/pxc56/bin/mysqld(_Z24do_handle_one_connectionP3THD+0x129)[0x72a5dd]
/pxc56/bin/mysqld(handle_one_connection+0x20)[0x72a705]
/usr/lib/libpthread.so.0(+0x80a2)[0x7f466df510a2]
/usr/lib/libc.so.6(clone+0x6d)[0x7f466c39643d]

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

You may download the Percona Server operations manual by visiting
http://www.percona.com/software/percona-server/. You may find information
in the manual which will help you identify the cause of the crash.
Writing a core file

==============================================================================================================================================================================================

2013-10-09 00:54:58 43148 [Note] WSREP: wsrep_abort_thd, by: 139940104804096, victim: 139940105602816
2013-10-09 00:54:58 43148 [Note] WSREP: abort transaction: BF: DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc` victim: COMMIT
2013-10-09 00:54:58 43148 [Note] WSREP: cluster conflict due to high priority abort for threads:
2013-10-09 00:54:58 43148 [Note] WSREP: Winning thread:
   THD: 15, mode: total order, state: executing, conflict: no conflict, seqno: 638
   SQL: DROP TABLE `table100_innodb_key_pk_parts_2_int_autoinc`
2013-10-09 00:54:58 43148 [Note] WSREP: Victim thread:
   THD: 12, mode: local, state: committing, conflict: no conflict, seqno: 639
   SQL: COMMIT
............
.................

2013-10-09 00:54:59 43148 [Note] WSREP: Victim thread:
2013-10-09 00:54:59 43148 [Note] WSREP: replay trx: COMMIT 639
2013-10-09 00:54:59 43148 [Note] WSREP: trx_replay successful for: 12 139940105602816

It looks like the it showed trx_replay successful for a victim
thread.

Attaching full log.