[Note] Slave SQL: Rolling back unfinished transaction (no COMMIT or ROLLBACK in relay log). A probable cause is that the master died while writing the transaction to its binary log, thus rolled back too. Error_code: 0 | mysqld got signal 11 ; | handle_fatal_signal (sig=11) in inline_mysql_prlock_rdlock

Bug #1384558 reported by Roel Van de Paar
8
This bug affects 1 person
Affects Status Importance Assigned to Milestone
Percona Server moved to https://jira.percona.com/projects/PS
Status tracked in 5.7
5.1
Won't Fix
Undecided
Unassigned
5.5
New
Undecided
Unassigned
5.6
New
Undecided
Unassigned
5.7
New
Undecided
Unassigned

Bug Description

2014-10-18 11:28:26 25769 [Note] /sda/Percona-Server-5.6.21-rel69.0-681.Linux.x86_64-debug/bin/mysqld: ready for connections.
Version: '5.6.21-69.0-debug' socket: '/dev/shm/235746/882/socket.sock' port: 56678 Percona Server (GPL), Release 69.0, Revision 681, DEBUG BINARY
2014-10-18 11:28:27 25769 [Warning] Did not write failed 'GRANT ALL ON *.* TO 'test2'@'localhost'' into binary log while granting/revoking privileges in databases.
InnoDB: user stopword table not_defined does not exist.
2014-10-18 11:28:32 25769 [Note] Slave SQL: Rolling back unfinished transaction (no COMMIT or ROLLBACK in relay log). A probable cause is that the master died while writing the transaction to its binary log, thus rolled back too. Error_code: 0
00:28:32 UTC - mysqld got signal 11 ;

Thread 1 (Thread 0x7f37c5811700 (LWP 25867)):
+bt
#0 0x00007f37debda771 in pthread_kill () from /lib64/libpthread.so.0
#1 0x0000000000ab7586 in my_write_core (sig=11) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/mysys/stacktrace.c:422
#2 0x000000000072f9ff in handle_fatal_signal (sig=11) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/signal_handler.cc:236
#3 <signal handler called>
#4 0x000000000071a7f1 in inline_mysql_prlock_rdlock (that=0x190, src_file=0xf27e68 "/mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/mdl.cc", src_line=1931) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/include/mysql/psi/mysql_thread.h:892
#5 0x000000000071c29c in MDL_lock::has_pending_conflicting_lock (this=0x0, type=MDL_INTENTION_EXCLUSIVE) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/mdl.cc:1931
#6 0x000000000071e293 in MDL_ticket::has_pending_conflicting_lock (this=0x7f37749271b0) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/mdl.cc:3031
#7 0x00000000007be997 in mysql_ha_flush (thd=0x1b4c510) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_handler.cc:982
#8 0x0000000000774586 in open_tables (thd=0x1b4c510, start=0x7f37c580ea70, counter=0x1b4eb18, flags=0, prelocking_strategy=0x7f37c580eab0) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_base.cc:5137
#9 0x0000000000775932 in open_normal_and_derived_tables (thd=0x1b4c510, tables=0x7f37740050e8, flags=0) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_base.cc:5913
#10 0x00000000007ea71c in execute_sqlcom_select (thd=0x1b4c510, all_tables=0x7f37740050e8) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_parse.cc:5570
#11 0x00000000007e2de9 in mysql_execute_command (thd=0x1b4c510) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_parse.cc:2958
#12 0x00000000007ed193 in mysql_parse (thd=0x1b4c510, rawbuf=0x7f3774004e90 "select * from t2 where firstname='john' and firstname = binary 'john'", length=69, parser_state=0x7f37c580fd50) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_parse.cc:6773
#13 0x00000000007df5fc in dispatch_command (command=COM_QUERY, thd=0x1b4c510, packet=0x233c401 "select * from t2 where firstname='john' and firstname = binary 'john';", packet_length=70) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_parse.cc:1432
#14 0x00000000007de528 in do_command (thd=0x1b4c510) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/sql_parse.cc:1049
#15 0x00000000008c2c30 in threadpool_process_request (thd=0x1b4c510) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/threadpool_common.cc:311
#16 0x00000000008c5572 in handle_event (connection=0x22c6670) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/threadpool_unix.cc:1553
#17 0x00000000008c57a2 in worker_main (param=0x1898400 <all_groups+2048>) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/sql/threadpool_unix.cc:1606
#18 0x0000000000dc5bac in pfs_spawn_thread (arg=0x2303960) at /mnt/workspace/percona-server-5.6-binaries-debug-yassl/label_exp/centos6-64/percona-server-5.6.21-69.0/storage/perfschema/pfs.cc:1860
#19 0x00007f37debd5df3 in start_thread () from /lib64/libpthread.so.0
#20 0x00007f37dd89f01d in clone () from /lib64/libc.so.6

Tags: qa
Revision history for this message
Roel Van de Paar (roel11) wrote :

Query (7f3774004e90): select * from t2 where firstname='john' and firstname = binary 'john'

Revision history for this message
Roel Van de Paar (roel11) wrote :

There was no master (single threaded, single mysqld run)

Revision history for this message
Roel Van de Paar (roel11) wrote :
summary: 2014-10-18 11:28:32 25769 [Note] Slave SQL: Rolling back unfinished
transaction (no COMMIT or ROLLBACK in relay log). A probable cause is
that the master died while writing the transaction to its binary log,
- thus rolled back too. Error_code: 0 | 00:28:32 UTC - mysqld got signal
- 11 ; | handle_fatal_signal (sig=11) in inline_mysql_prlock_rdlock
+ thus rolled back too. Error_code: 0 | mysqld got signal 11 ; |
+ handle_fatal_signal (sig=11) in inline_mysql_prlock_rdlock
summary: - 2014-10-18 11:28:32 25769 [Note] Slave SQL: Rolling back unfinished
- transaction (no COMMIT or ROLLBACK in relay log). A probable cause is
- that the master died while writing the transaction to its binary log,
- thus rolled back too. Error_code: 0 | mysqld got signal 11 ; |
- handle_fatal_signal (sig=11) in inline_mysql_prlock_rdlock
+ [Note] Slave SQL: Rolling back unfinished transaction (no COMMIT or
+ ROLLBACK in relay log). A probable cause is that the master died while
+ writing the transaction to its binary log, thus rolled back too.
+ Error_code: 0 | mysqld got signal 11 ; | handle_fatal_signal (sig=11)
+ in inline_mysql_prlock_rdlock
Revision history for this message
Roel Van de Paar (roel11) wrote :

Laurynas, we keep seeing this bug in our runs. No testcase yet. It would be good to be able to filter this one. We can filter it by string. Would it be possible to check the coredump for this one to see if you can see what the problem is in the code?

Revision history for this message
Laurynas Biveinis (laurynas-biveinis) wrote :

Please filter it for now

Revision history for this message
Shahriyar Rzayev (rzayev-sehriyar) wrote :

Percona now uses JIRA for bug reports so this bug report is migrated to: https://jira.percona.com/browse/PS-3200

To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.