Details
Description
This issue is similar to MDEV-20945, but we need to set wsrep_trx_fragment_size to reproduce the issue with Galera cluster
CREATE TABLE t (c DOUBLE,c2 INT,PRIMARY KEY(c));
|
SELECT GET_LOCK('a',1);
|
START TRANSACTION;
|
SET SESSION wsrep_trx_fragment_size=1;
|
INSERT INTO t VALUES(1,1), (1,2);
|
SELECT RELEASE_ALL_LOCKS();
|
|
10.6.0 74281fe1fb0faf444aec3744b90995156f9f58f9 (Optimized) |
Core was generated by `/test/GAL_MD240221-mariadb-10.6.0-linux-x86_64-opt/bin/mysqld --defaults-file=/'.
|
Program terminated with signal SIGSEGV, Segmentation fault.
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11)
|
at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
[Current thread is 1 (Thread 0x147cadccf700 (LWP 2135279))]
|
(gdb) bt
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11) at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
#1 0x000055cdd403f67f in my_write_core (sig=sig@entry=11) at /test/10.6_opt/mysys/stacktrace.c:424
|
#2 0x000055cdd3ab1510 in handle_fatal_signal (sig=11) at /test/10.6_opt/sql/signal_handler.cc:331
|
#3 <signal handler called>
|
#4 MDL_lock::hog_lock_types_bitmap (this=0x147c70021ec8) at /test/10.6_opt/sql/mdl.cc:587
|
#5 MDL_lock::reschedule_waiters (this=0x147c70021ec8) at /test/10.6_opt/sql/mdl.cc:1273
|
#6 0x000055cdd39b2bc6 in MDL_lock::remove_ticket (this=0x147c70021ec8, pins=0x55cdd6c24468, list=list@entry=&MDL_lock::m_granted, ticket=ticket@entry=0x55cdd6ca8fb0) at /test/10.6_opt/sql/mdl.cc:1811
|
#7 0x000055cdd39b3529 in MDL_context::release_lock (this=<optimized out>, duration=<optimized out>, ticket=0x55cdd6ca8fb0) at /test/10.6_opt/sql/mdl.cc:2822
|
#8 0x000055cdd39b3571 in MDL_context::release_lock (this=<optimized out>, ticket=<optimized out>) at /test/10.6_opt/sql/mdl.cc:2842
|
#9 0x000055cdd3b186bd in Item_func_release_all_locks::val_int (this=<optimized out>) at /test/10.6_opt/sql/item_func.cc:4294
|
#10 0x000055cdd3a19e1d in Type_handler::Item_send_long (this=<optimized out>, item=0x147c70010988, protocol=0x147c700011b0, buf=<optimized out>) at /test/10.6_opt/sql/sql_type.cc:7392
|
#11 0x000055cdd37de910 in Protocol::send_result_set_row (this=this@entry=0x147c700011b0, row_items=row_items@entry=0x147c70010658) at /test/10.6_opt/sql/protocol.cc:1331
|
#12 0x000055cdd384fba7 in select_send::send_data (this=0x147c70011330, items=@0x147c70010658: {<base_list> = {<Sql_alloc> = {<No data fields>}, first = 0x147c70010a50, last = 0x147c70010a50, elements = 1}, <No data fields>}) at /test/10.6_opt/sql/sql_class.cc:3019
|
#13 0x000055cdd390d226 in select_result_sink::send_data_with_check (u=<optimized out>, sent=0, items=<optimized out>, this=<optimized out>) at /test/10.6_opt/sql/sql_class.h:5529
|
#14 select_result_sink::send_data_with_check (sent=0, u=<optimized out>, items=<optimized out>, this=<optimized out>) at /test/10.6_opt/sql/sql_class.h:5519
|
#15 JOIN::exec_inner (this=0x147c70011358) at /test/10.6_opt/sql/sql_select.cc:4345
|
#16 0x000055cdd390d519 in JOIN::exec (this=this@entry=0x147c70011358) at /test/10.6_opt/sql/sql_select.cc:4257
|
#17 0x000055cdd390b5da in mysql_select (thd=0x147c70000c58, tables=0x0, fields=<optimized out>, conds=0x0, og_num=0, order=0x0, group=0x0, having=0x0, proc_param=0x0, select_options=2147748608, result=0x147c70011330, unit=0x147c70004c68, select_lex=0x147c70010508) at /test/10.6_opt/sql/sql_select.cc:4730
|
#18 0x000055cdd390bfa7 in handle_select (thd=thd@entry=0x147c70000c58, lex=lex@entry=0x147c70004ba0, result=result@entry=0x147c70011330, setup_tables_done_option=setup_tables_done_option@entry=0) at /test/10.6_opt/sql/sql_select.cc:417
|
#19 0x000055cdd389acb1 in execute_sqlcom_select (thd=0x147c70000c58, all_tables=0x0) at /test/10.6_opt/sql/sql_parse.cc:6204
|
#20 0x000055cdd38a8983 in mysql_execute_command (thd=0x147c70000c58) at /test/10.6_opt/sql/sql_parse.cc:3900
|
#21 0x000055cdd389583f in mysql_parse (thd=thd@entry=0x147c70000c58, rawbuf=rawbuf@entry=0x147c70010470 "SELECT RELEASE_ALL_LOCKS()", length=length@entry=26, parser_state=parser_state@entry=0x147cadcce400) at /test/10.6_opt/sql/sql_parse.cc:7972
|
#22 0x000055cdd38952b6 in wsrep_mysql_parse (thd=0x147c70000c58, rawbuf=0x147c70010470 "SELECT RELEASE_ALL_LOCKS()", length=26, parser_state=0x147cadcce400) at /test/10.6_opt/sql/sql_parse.cc:7786
|
#23 0x000055cdd38a26ee in dispatch_command (command=COM_QUERY, thd=0x147c70000c58, packet=0x147c700080d9 "", packet_length=<optimized out>, blocking=<optimized out>) at /test/10.6_opt/sql/sql_class.h:1295
|
#24 0x000055cdd38a3136 in do_command (thd=0x147c70000c58, blocking=blocking@entry=true) at /test/10.6_opt/sql/sql_parse.cc:1397
|
#25 0x000055cdd39a91dd in do_handle_one_connection (connect=<optimized out>, connect@entry=0x55cdd6ccb3f8, put_in_cache=put_in_cache@entry=true) at /test/10.6_opt/sql/sql_connect.cc:1410
|
#26 0x000055cdd39a968d in handle_one_connection (arg=arg@entry=0x55cdd6ccb3f8) at /test/10.6_opt/sql/sql_connect.cc:1312
|
#27 0x000055cdd3d30846 in pfs_spawn_thread (arg=0x55cdd6cdeb68) at /test/10.6_opt/storage/perfschema/pfs.cc:2201
|
#28 0x0000147ce397d609 in start_thread (arg=<optimized out>) at pthread_create.c:477
|
#29 0x0000147ce356c293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
|
|
10.6.0 208233be5af55072d7ef80c37ddbc664bc51f342 (Debug) |
Core was generated by `/test/GAL_MD230221-mariadb-10.6.0-linux-x86_64-dbg/bin/mysqld --defaults-file=/'.
|
Program terminated with signal SIGSEGV, Segmentation fault.
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11)
|
at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
[Current thread is 1 (Thread 0x154c5c96a700 (LWP 2121372))]
|
(gdb) bt
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11) at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
#1 0x0000559915c254db in my_write_core (sig=sig@entry=11) at /test/10.6_dbg/mysys/stacktrace.c:424
|
#2 0x00005599153b52df in handle_fatal_signal (sig=11) at /test/10.6_dbg/sql/signal_handler.cc:330
|
#3 <signal handler called>
|
#4 0x00005599152563e4 in ilist<MDL_ticket, void>::erase (pos={node_ = 0x154bf40083b8}, this=0x154bf404ab20) at /test/10.6_dbg/include/ilist.h:207
|
#5 ilist<MDL_ticket, void>::remove (value=@0x154bf40083b0: {<MDL_wait_for_subgraph> = {_vptr.MDL_wait_for_subgraph = 0x154c4c026df0}, <ilist_node<void>> = {next = 0x154bf40008d0, prev = 0x0}, next_in_context = 0x0, prev_in_context = 0x154bf4000fe8, m_type = MDL_SHARED_NO_WRITE, m_duration = MDL_EXPLICIT, m_ctx = 0x154bf4000ee8, m_lock = 0x154bf404a918, m_psi = 0x0}, this=0x154bf404ab20) at /test/10.6_dbg/include/ilist.h:207
|
#6 MDL_lock::Ticket_list::remove_ticket (this=this@entry=0x154bf404ab20, ticket=ticket@entry=0x154bf40083b0) at /test/10.6_dbg/sql/mdl.cc:1247
|
#7 0x0000559915257d27 in MDL_lock::remove_ticket (this=this@entry=0x154bf404a918, pins=0x559917f94fb0, list=list@entry=&MDL_lock::m_granted, ticket=ticket@entry=0x154bf40083b0) at /test/10.6_dbg/sql/mdl.cc:1792
|
#8 0x0000559915257e3c in MDL_context::release_lock (this=this@entry=0x154bf4000ee8, duration=duration@entry=MDL_EXPLICIT, ticket=ticket@entry=0x154bf40083b0) at /test/10.6_dbg/sql/mdl.cc:2822
|
#9 0x0000559915257f1b in MDL_context::release_lock (this=this@entry=0x154bf4000ee8, ticket=0x154bf40083b0) at /test/10.6_dbg/sql/mdl.cc:2842
|
#10 0x000055991544cd20 in Item_func_release_all_locks::val_int (this=<optimized out>) at /test/10.6_dbg/sql/item_func.cc:4294
|
#11 0x00005599152e7f8b in Type_handler::Item_send_long (this=<optimized out>, item=0x154bf4014078, protocol=0x154bf4001398, buf=<optimized out>) at /test/10.6_dbg/sql/sql_type.cc:7392
|
#12 0x00005599152f11af in Type_handler_long::Item_send (this=<optimized out>, item=<optimized out>, protocol=<optimized out>, buf=<optimized out>) at /test/10.6_dbg/sql/sql_type.h:5595
|
#13 0x0000559914fe64d8 in Item::send (this=0x154bf4014078, protocol=0x154bf4001398, buffer=0x154c5c968240) at /test/10.6_dbg/sql/item.h:1066
|
#14 0x0000559914fe39da in Protocol::send_result_set_row (this=this@entry=0x154bf4001398, row_items=row_items@entry=0x154bf4013d48) at /test/10.6_dbg/sql/protocol.cc:1331
|
#15 0x000055991507da3b in select_send::send_data (this=0x154bf4014a20, items=@0x154bf4013d48: {<base_list> = {<Sql_alloc> = {<No data fields>}, first = 0x154bf4014140, last = 0x154bf4014140, elements = 1}, <No data fields>}) at /test/10.6_dbg/sql/sql_class.cc:3019
|
#16 0x0000559915170c40 in select_result_sink::send_data_with_check (sent=0, u=<optimized out>, items=<optimized out>, this=<optimized out>) at /test/10.6_dbg/sql/sql_class.h:5529
|
#17 JOIN::exec_inner (this=this@entry=0x154bf4014a48) at /test/10.6_dbg/sql/sql_select.cc:4344
|
#18 0x0000559915171b2d in JOIN::exec (this=this@entry=0x154bf4014a48) at /test/10.6_dbg/sql/sql_select.cc:4256
|
#19 0x000055991516fd89 in mysql_select (thd=thd@entry=0x154bf4000db8, tables=0x0, fields=@0x154bf4013d48: {<base_list> = {<Sql_alloc> = {<No data fields>}, first = 0x154bf4014140, last = 0x154bf4014140, elements = 1}, <No data fields>}, conds=0x0, og_num=0, order=0x0, group=0x0, having=0x0, proc_param=0x0, select_options=2147748608, result=0x154bf4014a20, unit=0x154bf4004f88, select_lex=0x154bf4013bf8) at /test/10.6_dbg/sql/sql_select.cc:4729
|
#20 0x0000559915170050 in handle_select (thd=thd@entry=0x154bf4000db8, lex=lex@entry=0x154bf4004ec0, result=result@entry=0x154bf4014a20, setup_tables_done_option=setup_tables_done_option@entry=0) at /test/10.6_dbg/sql/sql_select.cc:417
|
#21 0x00005599150e21e0 in execute_sqlcom_select (thd=thd@entry=0x154bf4000db8, all_tables=0x0) at /test/10.6_dbg/sql/sql_parse.cc:6204
|
#22 0x00005599150eeee2 in mysql_execute_command (thd=thd@entry=0x154bf4000db8) at /test/10.6_dbg/sql/sql_parse.cc:3900
|
#23 0x00005599150db360 in mysql_parse (thd=thd@entry=0x154bf4000db8, rawbuf=rawbuf@entry=0x154bf4013b60 "SELECT RELEASE_ALL_LOCKS()", length=length@entry=26, parser_state=parser_state@entry=0x154c5c9693d0) at /test/10.6_dbg/sql/sql_parse.cc:7972
|
#24 0x00005599150dacbc in wsrep_mysql_parse (thd=thd@entry=0x154bf4000db8, rawbuf=0x154bf4013b60 "SELECT RELEASE_ALL_LOCKS()", length=26, parser_state=parser_state@entry=0x154c5c9693d0) at /test/10.6_dbg/sql/sql_parse.cc:7786
|
#25 0x00005599150e92a0 in dispatch_command (command=command@entry=COM_QUERY, thd=thd@entry=0x154bf4000db8, packet=packet@entry=0x154bf400b319 "", packet_length=packet_length@entry=26, blocking=blocking@entry=true) at /test/10.6_dbg/sql/sql_class.h:1295
|
#26 0x00005599150ec70c in do_command (thd=0x154bf4000db8, blocking=blocking@entry=true) at /test/10.6_dbg/sql/sql_parse.cc:1397
|
#27 0x0000559915249bf5 in do_handle_one_connection (connect=<optimized out>, connect@entry=0x55991805aeb8, put_in_cache=put_in_cache@entry=true) at /test/10.6_dbg/sql/sql_connect.cc:1410
|
#28 0x000055991524a2fb in handle_one_connection (arg=arg@entry=0x55991805aeb8) at /test/10.6_dbg/sql/sql_connect.cc:1312
|
#29 0x00005599157015cd in pfs_spawn_thread (arg=0x5599180d26c8) at /test/10.6_dbg/storage/perfschema/pfs.cc:2201
|
#30 0x0000154c6e8a5609 in start_thread (arg=<optimized out>) at pthread_create.c:477
|
#31 0x0000154c6e494293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
|
|
10.5.10 85bec9d691bb69ed20beb565b03d5585b94624fe (Optimized) |
Core was generated by `/test/GAL_MD240221-mariadb-10.5.10-linux-x86_64-opt/bin/mysqld --defaults-file='.
|
Program terminated with signal SIGSEGV, Segmentation fault.
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11)
|
at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
[Current thread is 1 (Thread 0x152674098700 (LWP 2822567))]
|
(gdb) bt
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11) at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
#1 0x0000562d5adc02ef in my_write_core (sig=sig@entry=11) at /test/10.5_opt/mysys/stacktrace.c:424
|
#2 0x0000562d5a7ebb80 in handle_fatal_signal (sig=11) at /test/10.5_opt/sql/signal_handler.cc:330
|
#3 <signal handler called>
|
#4 MDL_lock::hog_lock_types_bitmap (this=0x15260c0218f8) at /test/10.5_opt/sql/mdl.cc:587
|
#5 MDL_lock::reschedule_waiters (this=0x15260c0218f8) at /test/10.5_opt/sql/mdl.cc:1273
|
#6 0x0000562d5a6ed136 in MDL_lock::remove_ticket (this=0x15260c0218f8, pins=0x562d5c8c08b8, list=list@entry=&MDL_lock::m_granted, ticket=ticket@entry=0x562d5c943fb0) at /test/10.5_opt/sql/mdl.cc:1811
|
#7 0x0000562d5a6eda99 in MDL_context::release_lock (this=<optimized out>, duration=<optimized out>, ticket=0x562d5c943fb0) at /test/10.5_opt/sql/mdl.cc:2822
|
#8 0x0000562d5a6edae1 in MDL_context::release_lock (this=<optimized out>, ticket=<optimized out>) at /test/10.5_opt/sql/mdl.cc:2842
|
#9 0x0000562d5a852d2d in Item_func_release_all_locks::val_int (this=<optimized out>) at /test/10.5_opt/sql/item_func.cc:4294
|
#10 0x0000562d5a75431d in Type_handler::Item_send_long (this=<optimized out>, item=0x15260c0108d8, protocol=0x15260c0011a0, buf=<optimized out>) at /test/10.5_opt/sql/sql_type.cc:7392
|
#11 0x0000562d5a5199b0 in Protocol::send_result_set_row (this=this@entry=0x15260c0011a0, row_items=row_items@entry=0x15260c0105a8) at /test/10.5_opt/sql/protocol.cc:1085
|
#12 0x0000562d5a58abf7 in select_send::send_data (this=0x15260c011280, items=@0x15260c0105a8: {<base_list> = {<Sql_alloc> = {<No data fields>}, first = 0x15260c0109a0, last = 0x15260c0109a0, elements = 1}, <No data fields>}) at /test/10.5_opt/sql/sql_class.cc:3018
|
#13 0x0000562d5a647d16 in select_result_sink::send_data_with_check (u=<optimized out>, sent=0, items=<optimized out>, this=<optimized out>) at /test/10.5_opt/sql/sql_class.h:5328
|
#14 select_result_sink::send_data_with_check (sent=0, u=<optimized out>, items=<optimized out>, this=<optimized out>) at /test/10.5_opt/sql/sql_class.h:5318
|
#15 JOIN::exec_inner (this=0x15260c0112a8) at /test/10.5_opt/sql/sql_select.cc:4334
|
#16 0x0000562d5a648009 in JOIN::exec (this=this@entry=0x15260c0112a8) at /test/10.5_opt/sql/sql_select.cc:4246
|
#17 0x0000562d5a6460ca in mysql_select (thd=0x15260c000c58, tables=0x0, fields=<optimized out>, conds=0x0, og_num=0, order=0x0, group=0x0, having=0x0, proc_param=0x0, select_options=2147748608, result=0x15260c011280, unit=0x15260c004c40, select_lex=0x15260c010458) at /test/10.5_opt/sql/sql_select.cc:4719
|
#18 0x0000562d5a646a97 in handle_select (thd=thd@entry=0x15260c000c58, lex=lex@entry=0x15260c004b78, result=result@entry=0x15260c011280, setup_tables_done_option=setup_tables_done_option@entry=0) at /test/10.5_opt/sql/sql_select.cc:417
|
#19 0x0000562d5a5d5381 in execute_sqlcom_select (thd=0x15260c000c58, all_tables=0x0) at /test/10.5_opt/sql/sql_parse.cc:6282
|
#20 0x0000562d5a5e3617 in mysql_execute_command (thd=0x15260c000c58) at /test/10.5_opt/sql/sql_parse.cc:3978
|
#21 0x0000562d5a5cff0f in mysql_parse (thd=thd@entry=0x15260c000c58, rawbuf=rawbuf@entry=0x15260c0103c0 "SELECT RELEASE_ALL_LOCKS()", length=length@entry=26, parser_state=parser_state@entry=0x152674097400, is_com_multi=is_com_multi@entry=false, is_next_command=is_next_command@entry=false) at /test/10.5_opt/sql/sql_parse.cc:8063
|
#22 0x0000562d5a5cf94c in wsrep_mysql_parse (thd=0x15260c000c58, rawbuf=0x15260c0103c0 "SELECT RELEASE_ALL_LOCKS()", length=26, parser_state=0x152674097400, is_com_multi=<optimized out>, is_next_command=<optimized out>) at /test/10.5_opt/sql/sql_parse.cc:7866
|
#23 0x0000562d5a5dd607 in dispatch_command (command=COM_QUERY, thd=0x15260c000c58, packet=<optimized out>, packet_length=<optimized out>, is_com_multi=<optimized out>, is_next_command=<optimized out>) at /test/10.5_opt/sql/sql_class.h:1257
|
#24 0x0000562d5a5ddea7 in do_command (thd=0x15260c000c58) at /test/10.5_opt/sql/sql_parse.cc:1370
|
#25 0x0000562d5a6e3781 in do_handle_one_connection (connect=<optimized out>, connect@entry=0x562d5c9547f8, put_in_cache=put_in_cache@entry=true) at /test/10.5_opt/sql/sql_connect.cc:1410
|
#26 0x0000562d5a6e3bfd in handle_one_connection (arg=arg@entry=0x562d5c9547f8) at /test/10.5_opt/sql/sql_connect.cc:1312
|
#27 0x0000562d5aa6c4b6 in pfs_spawn_thread (arg=0x562d5c97c208) at /test/10.5_opt/storage/perfschema/pfs.cc:2201
|
#28 0x000015268846d609 in start_thread (arg=<optimized out>) at pthread_create.c:477
|
#29 0x000015268805c293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
|
Bug confirmed present in:
MariaDB: 10.6.0 (opt), 10.6.0 (dbg), 10.5.10 (opt), 10.5.10 (dbg)
Bug confirmed not present in:
MariaDB: 10.2.38, 10.3.29, 10.4.19
Attachments
Issue Links
- relates to
-
MDEV-20945 BACKUP UNLOCK + FTWRL assertion failure | SIGSEGV in I_P_List from MDL_context::release_lock on INSERT w/ BACKUP LOCK (on optimized builds) | Assertion `ticket->m_duration == MDL_EXPLICIT' failed.
-
- Closed
-
-
-
- Closed
-
Activity
Found similar assertion with slightly different stack.
SELECT GET_LOCK ("lock_bug26141_wait", 1000) INTO @a;
|
CREATE TABLE t (a BINARY (211));
|
CREATE TABLE t1 (c1 VARCHAR(10));
|
SET SESSION wsrep_trx_fragment_size=200;
|
SET AUTOCOMMIT=OFF;
|
INSERT INTO t1 VALUES(0xAADF);
|
SELECT QUOTE (REPLACE ('[SELECT COUNT(*) FROM t1 WHERE a=LEFT (@inserted_value, 130)]=1', CONCAT ('[', 'SELECT COUNT(*) FROM t1 WHERE a=LEFT (@inserted_value, 130)', ']'), '1'));
|
INSERT INTO t1 VALUES(123.4567e-1);
|
INSERT INTO t VALUES(34075,-70);
|
CREATE TABLE t570 (c1 INT);
|
SELECT RELEASE_ALL_LOCKS()=2 AS expect_1;
|
Leads to:
|
10.6.0 2f53ad4b7dec8c69068b9db20a0f6084fb4bd0b8 (Debug) |
mysqld: /test/10.6_dbg/sql/mdl.cc:2841: void MDL_context::release_lock(MDL_ticket*): Assertion `ticket->m_duration == MDL_EXPLICIT' failed.
|
|
10.6.0 2f53ad4b7dec8c69068b9db20a0f6084fb4bd0b8 (Debug) |
Core was generated by `/test/GAL_MD150321-mariadb-10.6.0-linux-x86_64-dbg/bin/mysqld --defaults-file=/'.
|
Program terminated with signal SIGABRT, Aborted.
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=6)
|
at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
[Current thread is 1 (Thread 0x1550500c1700 (LWP 773860))]
|
(gdb) bt
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=6) at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
#1 0x000055beb5c952cd in my_write_core (sig=sig@entry=6) at /test/10.6_dbg/mysys/stacktrace.c:424
|
#2 0x000055beb5436124 in handle_fatal_signal (sig=6) at /test/10.6_dbg/sql/signal_handler.cc:331
|
#3 <signal handler called>
|
#4 __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
|
#5 0x00001550848b2859 in __GI_abort () at abort.c:79
|
#6 0x00001550848b2729 in __assert_fail_base (fmt=0x155084a48588 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=0x55beb5e34a40 "ticket->m_duration == MDL_EXPLICIT", file=0x55beb5e33d79 "/test/10.6_dbg/sql/mdl.cc", line=2841, function=<optimized out>) at assert.c:92
|
#7 0x00001550848c3f36 in __GI___assert_fail (assertion=assertion@entry=0x55beb5e34a40 "ticket->m_duration == MDL_EXPLICIT", file=file@entry=0x55beb5e33d79 "/test/10.6_dbg/sql/mdl.cc", line=line@entry=2841, function=function@entry=0x55beb5e34a68 "void MDL_context::release_lock(MDL_ticket*)") at assert.c:101
|
#8 0x000055beb52db987 in MDL_context::release_lock (this=this@entry=0x15500c000ee8, ticket=0x155060035900) at /test/10.6_dbg/sql/mdl.cc:2841
|
#9 0x000055beb54cd0e0 in Item_func_release_all_locks::val_int (this=<optimized out>) at /test/10.6_dbg/sql/item_func.cc:4294
|
#10 0x000055beb548475b in Arg_comparator::compare_int_unsigned_signed (this=0x15500c014328) at /test/10.6_dbg/sql/item_cmpfunc.cc:1010
|
#11 0x000055beb5486b4f in Arg_comparator::compare (this=0x15500c014328) at /test/10.6_dbg/sql/item_cmpfunc.h:102
|
#12 Item_func_eq::val_int (this=0x15500c014258) at /test/10.6_dbg/sql/item_cmpfunc.cc:1775
|
#13 0x000055beb5368d67 in Type_handler::Item_send_long (this=<optimized out>, item=0x15500c014258, protocol=0x15500c0013a0, buf=<optimized out>) at /test/10.6_dbg/sql/sql_type.cc:7392
|
#14 0x000055beb5371f8b in Type_handler_long::Item_send (this=<optimized out>, item=<optimized out>, protocol=<optimized out>, buf=<optimized out>) at /test/10.6_dbg/sql/sql_type.h:5595
|
#15 0x000055beb5071d3a in Item::send (this=0x15500c014258, protocol=0x15500c0013a0, buffer=0x1550500bf2e0) at /test/10.6_dbg/sql/item.h:1066
|
#16 0x000055beb506f42e in Protocol::send_result_set_row (this=this@entry=0x15500c0013a0, row_items=row_items@entry=0x15500c013da0) at /test/10.6_dbg/sql/protocol.cc:1331
|
#17 0x000055beb5105bc1 in select_send::send_data (this=0x15500c014d88, items=@0x15500c013da0: {<base_list> = {<Sql_alloc> = {<No data fields>}, first = 0x15500c0144b0, last = 0x15500c0144b0, elements = 1}, <No data fields>}) at /test/10.6_dbg/sql/sql_class.cc:3020
|
#18 0x000055beb51f609b in select_result_sink::send_data_with_check (sent=0, u=<optimized out>, items=<optimized out>, this=<optimized out>) at /test/10.6_dbg/sql/sql_class.h:5554
|
#19 JOIN::exec_inner (this=this@entry=0x15500c014db0) at /test/10.6_dbg/sql/sql_select.cc:4345
|
#20 0x000055beb51f70fb in JOIN::exec (this=this@entry=0x15500c014db0) at /test/10.6_dbg/sql/sql_select.cc:4257
|
#21 0x000055beb51f51ce in mysql_select (thd=thd@entry=0x15500c000db8, tables=0x0, fields=@0x15500c013da0: {<base_list> = {<Sql_alloc> = {<No data fields>}, first = 0x15500c0144b0, last = 0x15500c0144b0, elements = 1}, <No data fields>}, conds=0x0, og_num=0, order=0x0, group=0x0, having=0x0, proc_param=0x0, select_options=2148272640, result=0x15500c014d88, unit=0x15500c004f90, select_lex=0x15500c013c50) at /test/10.6_dbg/sql/sql_select.cc:4733
|
#22 0x000055beb51f5495 in handle_select (thd=thd@entry=0x15500c000db8, lex=lex@entry=0x15500c004ec8, result=result@entry=0x15500c014d88, setup_tables_done_option=setup_tables_done_option@entry=0) at /test/10.6_dbg/sql/sql_select.cc:417
|
#23 0x000055beb51687d5 in execute_sqlcom_select (thd=thd@entry=0x15500c000db8, all_tables=0x0) at /test/10.6_dbg/sql/sql_parse.cc:6230
|
#24 0x000055beb51754be in mysql_execute_command (thd=thd@entry=0x15500c000db8) at /test/10.6_dbg/sql/sql_parse.cc:3926
|
#25 0x000055beb5161b82 in mysql_parse (thd=thd@entry=0x15500c000db8, rawbuf=rawbuf@entry=0x15500c013ba0 "SELECT release_all_locks()=2 AS expect_1", length=length@entry=40, parser_state=parser_state@entry=0x1550500c0410) at /test/10.6_dbg/sql/sql_parse.cc:7998
|
#26 0x000055beb516151b in wsrep_mysql_parse (thd=thd@entry=0x15500c000db8, rawbuf=0x15500c013ba0 "SELECT release_all_locks()=2 AS expect_1", length=40, parser_state=parser_state@entry=0x1550500c0410) at /test/10.6_dbg/sql/sql_parse.cc:7812
|
#27 0x000055beb51705ec in dispatch_command (command=command@entry=COM_QUERY, thd=thd@entry=0x15500c000db8, packet=packet@entry=0x15500c00b359 "", packet_length=packet_length@entry=40, blocking=blocking@entry=true) at /test/10.6_dbg/sql/sql_class.h:1318
|
#28 0x000055beb51739b0 in do_command (thd=0x15500c000db8, blocking=blocking@entry=true) at /test/10.6_dbg/sql/sql_parse.cc:1397
|
#29 0x000055beb52cdaf1 in do_handle_one_connection (connect=<optimized out>, connect@entry=0x55beb8e797a8, put_in_cache=put_in_cache@entry=true) at /test/10.6_dbg/sql/sql_connect.cc:1410
|
#30 0x000055beb52ce0f9 in handle_one_connection (arg=arg@entry=0x55beb8e797a8) at /test/10.6_dbg/sql/sql_connect.cc:1312
|
#31 0x000055beb577ac01 in pfs_spawn_thread (arg=0x55beb8ef0f78) at /test/10.6_dbg/storage/perfschema/pfs.cc:2201
|
#32 0x0000155084dc0609 in start_thread (arg=<optimized out>) at pthread_create.c:477
|
#33 0x00001550849af293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
|
Found slightly different stack with BACKUP STAGE command.
BACKUP STAGE START;
|
START TRANSACTION; |
Leads to
|
10.10.2 e71aca8200558d590f8b1b8dbafa9693fcf5078b (Optimized) |
Core was generated by `/test/GAL_MD310822-mariadb-10.10.2-linux-x86_64-opt/bin/mysqld --defaults-file='.
|
Program terminated with signal SIGSEGV, Segmentation fault.
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11)
|
at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
[Current thread is 1 (Thread 0x153ebc0de700 (LWP 3977298))]
|
(gdb) bt
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11) at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
#1 0x00005626b183a68f in my_write_core (sig=sig@entry=11) at /test/10.10_opt/mysys/stacktrace.c:424
|
#2 0x00005626b138b360 in handle_fatal_signal (sig=11) at /test/10.10_opt/sql/signal_handler.cc:355
|
#3 <signal handler called>
|
#4 0x0000153e940008d0 in ?? ()
|
#5 0x00005626b1315aa5 in backup_end (thd=thd@entry=0x153e94000c58) at /test/10.10_opt/sql/backup.cc:398
|
#6 0x00005626b10f1b9b in THD::cleanup (this=this@entry=0x153e94000c58) at /test/10.10_opt/sql/sql_class.cc:1544
|
#7 0x00005626b1043239 in unlink_thd (thd=0x153e94000c58) at /test/10.10_opt/sql/mysqld.cc:2752
|
#8 0x00005626b1264a71 in do_handle_one_connection (connect=<optimized out>, connect@entry=0x5626b45b1778, put_in_cache=put_in_cache@entry=true) at /test/10.10_opt/sql/sql_connect.cc:1429
|
#9 0x00005626b1264f2d in handle_one_connection (arg=0x5626b45b1778) at /test/10.10_opt/sql/sql_connect.cc:1312
|
#10 0x0000153f059e9609 in start_thread (arg=<optimized out>) at pthread_create.c:477
|
#11 0x0000153f055d5133 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
|
|
10.10.2 e71aca8200558d590f8b1b8dbafa9693fcf5078b (Debug) |
Core was generated by `/test/GAL_MD310822-mariadb-10.10.2-linux-x86_64-dbg/bin/mysqld --defaults-file='.
|
Program terminated with signal SIGSEGV, Segmentation fault.
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11)
|
at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
[Current thread is 1 (Thread 0x14586c063700 (LWP 83550))]
|
(gdb) bt
|
#0 __pthread_kill (threadid=<optimized out>, signo=signo@entry=11) at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
|
#1 0x000056376d2b0767 in my_write_core (sig=sig@entry=11) at /test/10.10_dbg/mysys/stacktrace.c:424
|
#2 0x000056376cb6b5c3 in handle_fatal_signal (sig=11) at /test/10.10_dbg/sql/signal_handler.cc:355
|
#3 <signal handler called>
|
#4 0x000056376c9f30f2 in ilist<MDL_ticket, void>::erase (pos={node_ = 0x145830025878}, this=0x56376f264238) at /test/10.10_dbg/include/ilist.h:213
|
#5 ilist<MDL_ticket, void>::remove (value=@0x145830025870: {<MDL_wait_for_subgraph> = {_vptr.MDL_wait_for_subgraph = 0x145830024b90}, <ilist_node<void>> = {next = 0x1458300008d0, prev = 0x0}, next_in_context = 0x0, prev_in_context = 0x145830000f98, m_type = MDL_INTENTION_EXCLUSIVE, m_duration = MDL_EXPLICIT, m_ctx = 0x145830000e98, m_lock = 0x56376f264030, m_psi = 0x0}, this=0x56376f264238) at /test/10.10_dbg/include/ilist.h:213
|
#6 MDL_lock::Ticket_list::remove_ticket (this=this@entry=0x56376f264238, ticket=ticket@entry=0x145830025870) at /test/10.10_dbg/sql/mdl.cc:1283
|
#7 0x000056376c9f4812 in MDL_lock::remove_ticket (this=this@entry=0x56376f264030, pins=0x56376f4858f8, list=list@entry=&MDL_lock::m_granted, ticket=ticket@entry=0x145830025870) at /test/10.10_dbg/sql/mdl.cc:1828
|
#8 0x000056376c9f5563 in MDL_context::release_lock (this=this@entry=0x145830000e98, duration=duration@entry=MDL_EXPLICIT, ticket=ticket@entry=0x145830025870) at /test/10.10_dbg/sql/mdl.cc:2897
|
#9 0x000056376c9f5643 in MDL_context::release_lock (this=this@entry=0x145830000e98, ticket=ticket@entry=0x145830025870) at /test/10.10_dbg/sql/mdl.cc:2917
|
#10 0x000056376cad0698 in backup_end (thd=thd@entry=0x145830000d48) at /test/10.10_dbg/sql/backup.cc:398
|
#11 0x000056376c81647c in THD::cleanup (this=this@entry=0x145830000d48) at /test/10.10_dbg/sql/sql_class.cc:1544
|
#12 0x000056376c74b071 in unlink_thd (thd=0x145830000d48) at /test/10.10_dbg/sql/mysqld.cc:2752
|
#13 0x000056376c9e7b8e in do_handle_one_connection (connect=<optimized out>, connect@entry=0x56376f6d5518, put_in_cache=put_in_cache@entry=true) at /test/10.10_dbg/sql/sql_connect.cc:1429
|
#14 0x000056376c9e7ebf in handle_one_connection (arg=0x56376f6d5518) at /test/10.10_dbg/sql/sql_connect.cc:1312
|
#15 0x00001458a135c609 in start_thread (arg=<optimized out>) at pthread_create.c:477
|
#16 0x00001458a0f48133 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
|
When providing a test case, PLEASE do a complete one that one can repeat just by copying to 'main'!
I cannot reproduce the original problem with 10.6:
What I tried:
- copy the test case to mysql-test-run/suite/galara/t/skr.test
- add "--source include/have_galera_cluster.inc" to the start of the test case
- add "--source include/have_innodb.inc" to the start of the test case
mtr galera.skr
worker[1] Using MTR_BUILD_THREAD 301, with reserved ports 16020..16039
connection node_2;
connection node_1;
CREATE TABLE t (c DOUBLE,c2 INT,PRIMARY KEY(c));
SELECT GET_LOCK('a',1);
GET_LOCK('a',1)
1
START TRANSACTION;
SET SESSION wsrep_trx_fragment_size=1;
INSERT INTO t VALUES(1,1), (1,2);
galera.skr 'innodb' [ fail ]
Test ended at 2022-12-02 13:49:14
CURRENT_TEST: galera.skr
mysqltest: At line 8: query 'INSERT INTO t VALUES(1,1), (1,2)' failed: ER_LOCK_DEADLOCK (1213): Deadlock found when trying to get lock; try restarting transaction
Which is probably correct as one cannot insert two rows with same primary key.
I tried also run with the test case with:
- Change the insert to (1,1),(2,1) ; All ok
- Remove GET_LOCK - No change to above
- Remove SELECT RELEASE_ALL_LOCKS(); - No change (as this is never executed)
Tested as main/skr.test:
BACKUP STAGE START;
START TRANSACTION;
mtr main.skr - Works fine.
Then I copied it to the suite/galera/t and then I got a crash
The reason is this code in sql_connect.cc:
#ifdef WITH_WSREP
if (thd->wsrep_cs().state() == wsrep::client_state::s_exec)
wsrep_close(thd);
#endif /* WITH_WSREP */
wsrep_close() calls eventually wsrep_client_service::bf_rollback(), which calls MDL_context::release_explicit_locks which is NOT ALLOWED TO DO
This deletes all explicit locks in the server, including locks that are still active and that will be released
later by the sub system that is using them (which leads to crashes)!
In the above example, it is releasing the backup lock that later will be released when THD is deleted, which leads to a crash. I suspect we will have the similar issues with GLOBAL READ LOCK and other constructs that takes DDL locks.
Suggested fix (one of the following)
- Move the call to ws_close to the absolute end of THD::cleanup()
- Don't call bf_rollback() in ws_close, but instead in THD::cleanup()
and also remove the call m_thd->mdl_context.release_explicit_locks() from bf_rollback() !
I think it's is wrong that bf_rollback() does a lot of things that THD::cleanup() is already doing.
Better to remove this function altogether from beeing called by ws_close().
seppo This can't be solved by simply removing that all m_thd->mdl_context.release_explicit_locks() from bf_rollback(). Above problem is naturally solved but then we have lot of regressions on when we do bf kill. Adding check if (thd->killed) before doing that code piece that is also done in THD::cleanup() helps most of cases but not all, especially streaming replication tests. For some reason when we do bf kill we do not call THD::cleanup() before we enter back to do_command() and especially here:
/*
|
If this command does not return a result, then we
|
instruct wsrep_before_command() to skip result handling.
|
This causes BF aborted transaction to roll back but keep
|
the error state until next command which is able to return
|
a result to the client.
|
*/
|
if (unlikely(wsrep_service_started) &&
|
wsrep_before_command(thd, wsrep_command_no_result(command)))
|
{
|
/*
|
Aborted by background rollbacker thread.
|
Handle error here and jump straight to out.
|
Notice that thd->store_globals() is called
|
in wsrep_before_command().
|
*/
|
WSREP_LOG_THD(thd, "enter found BF aborted");
|
DBUG_ASSERT(!thd->mdl_context.has_locks());
|
DBUG_ASSERT(!thd->get_stmt_da()->is_set());
|
|
|
and naturally mdl_context.has_locks() at this point
|
Update: Removing code from bf_rollback and removing above assertion does not also fix the issue especially for TOI.
Note that explicit locks by GET_LOCK() are not supported in the cluster. Knowledge base has a page about it too: https://mariadb.com/kb/en/mariadb-galera-cluster-known-limitations/. This page is outdated, and needs to be updated, but it is still the fact that such explicit named locks are not supported in cluster environment. Adding explicit locking support is not in current road map either.
To fix this issue, the GET_LOCK() / RELEASE_LOCK() calls should be rejected with proper warning message.
monty I must say I do not fully understand what the assertion means. I can repeat the assertion on ::release_locks()
(gdb) where#0 __pthread_kill (threadid=<optimized out>, signo=6) at ../sysdeps/unix/sysv/linux/pthread_kill.c:54#1 0x0000561e59878901 in my_write_core (sig=6) at /home/jan/mysql/10.5/mysys/stacktrace.c:424#2 0x0000561e58f180bb in handle_fatal_signal (sig=6) at /home/jan/mysql/10.5/sql/signal_handler.cc:331#3 <signal handler called>#4 __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:49#5 0x00007f9d9e803864 in __GI_abort () at abort.c:79#6 0x00007f9d9e803749 in __assert_fail_base (fmt=0x7f9d9e98f458 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=0x561e599d8dd8 "ticket->m_duration == MDL_EXPLICIT", file=0x561e599d82a8 "/home/jan/mysql/10.5/sql/mdl.cc", line=2844, function=<optimized out>) at assert.c:92#7 0x00007f9d9e815a96 in __GI___assert_fail (assertion=0x561e599d8dd8 "ticket->m_duration == MDL_EXPLICIT", file=0x561e599d82a8 "/home/jan/mysql/10.5/sql/mdl.cc", line=2844, function=0x561e599d8e00 "void MDL_context::release_lock(MDL_ticket*)") at assert.c:101#8 0x0000561e58d69bce in MDL_context::release_lock (this=0x7f9d40000ed8, ticket=0x7f9d4003d220) at /home/jan/mysql/10.5/sql/mdl.cc:2844#9 0x0000561e58fb8077 in Item_func_release_all_locks::val_int (this=0x7f9d40015808) at /home/jan/mysql/10.5/sql/item_func.cc:4294#10 0x0000561e58e05a77 in Type_handler::Item_send_long (this=0x561e5a57b080 <type_handler_ulong>, item=0x7f9d40015808, protocol=0x7f9d40001388, buf=0x7f9d90257ff0) at /home/jan/mysql/10.5/sql/sql_type.cc:7392#11 0x0000561e58e146da in Type_handler_long::Item_send (this=0x561e5a57b080 <type_handler_ulong>, item=0x7f9d40015808, protocol=0x7f9d40001388, buf=0x7f9d90257ff0) at /home/jan/mysql/10.5/sql/sql_type.h:5595#12 0x0000561e58a7ef46 in Item::send (this=0x7f9d40015808, protocol=0x7f9d40001388, buffer=0x7f9d90257ff0) at /home/jan/mysql/10.5/sql/item.h:1066#13 0x0000561e58a78295 in Protocol::send_result_set_row (this=0x7f9d40001388, row_items=0x7f9d400154b8) at /home/jan/mysql/10.5/sql/protocol.cc:1085#14 0x0000561e58b3585d in select_send::send_data (this=0x7f9d400161d8, items=...) at /home/jan/mysql/10.5/sql/sql_class.cc:3018#15 0x0000561e58c51645 in select_result_sink::send_data_with_check (this=0x7f9d400161d8, items=..., u=0x7f9d40004f60, sent=0) at /home/jan/mysql/10.5/sql/sql_class.h:5328#16 0x0000561e58c06d76 in JOIN::exec_inner (this=0x7f9d40016200) at /home/jan/mysql/10.5/sql/sql_select.cc:4335#17 0x0000561e58c06605 in JOIN::exec (this=0x7f9d40016200) at /home/jan/mysql/10.5/sql/sql_select.cc:4247#18 0x0000561e58c07e39 in mysql_select (thd=0x7f9d40000db8, tables=0x0, fields=..., conds=0x0, og_num=0, order=0x0, group=0x0, having=0x0, proc_param=0x0, select_options=2147748608, result=0x7f9d400161d8, unit=0x7f9d40004f60, select_lex=0x7f9d40015368) at /home/jan/mysql/10.5/sql/sql_select.cc:4720#19 0x0000561e58bf78b7 in handle_select (thd=0x7f9d40000db8, lex=0x7f9d40004e98, result=0x7f9d400161d8, setup_tables_done_option=0) at /home/jan/mysql/10.5/sql/sql_select.cc:417#20 0x0000561e58bba27b in execute_sqlcom_select (thd=0x7f9d40000db8, all_tables=0x0) at /home/jan/mysql/10.5/sql/sql_parse.cc:6282#21 0x0000561e58bb1305 in mysql_execute_command (thd=0x7f9d40000db8) at /home/jan/mysql/10.5/sql/sql_parse.cc:3978#22 0x0000561e58bbf122 in mysql_parse (thd=0x7f9d40000db8, rawbuf=0x7f9d400152d0 "SELECT RELEASE_ALL_LOCKS()", length=26, parser_state=0x7f9d902593f0, is_com_multi=false, is_next_command=false) at /home/jan/mysql/10.5/sql/sql_parse.cc:8063#23 0x0000561e58bbe825 in wsrep_mysql_parse (thd=0x7f9d40000db8, rawbuf=0x7f9d400152d0 "SELECT RELEASE_ALL_LOCKS()", length=26, parser_state=0x7f9d902593f0, is_com_multi=false, is_next_command=false) at /home/jan/mysql/10.5/sql/sql_parse.cc:7866#24 0x0000561e58baaf8c in dispatch_command (command=COM_QUERY, thd=0x7f9d40000db8, packet=0x7f9d4000b589 "", packet_length=26, is_com_multi=false, is_next_command=false) at /home/jan/mysql/10.5/sql/sql_parse.cc:1875#25 0x0000561e58ba987b in do_command (thd=0x7f9d40000db8) at /home/jan/mysql/10.5/sql/sql_parse.cc:1370#26 0x0000561e58d57a91 in do_handle_one_connection (connect=0x561e5c290828, put_in_cache=true) at /home/jan/mysql/10.5/sql/sql_connect.cc:1410#27 0x0000561e58d577f4 in handle_one_connection (arg=0x561e5c27fa28) at /home/jan/mysql/10.5/sql/sql_connect.cc:1312#28 0x0000561e592b6d53 in pfs_spawn_thread (arg=0x561e5bae1528) at /home/jan/mysql/10.5/storage/perfschema/pfs.cc:2201#29 0x00007f9d9ed23590 in start_thread (arg=0x7f9d9025a640) at pthread_create.c:463#30 0x00007f9d9e8f6223 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95(gdb) p *ticket$1 = {<MDL_wait_for_subgraph> = {_vptr.MDL_wait_for_subgraph = 0x7f9ab9d7c27d}, <ilist_node<void>> = {next = 0x7f9d400008d0, prev = 0x2d6264616972616d}, next_in_context = 0x696c6172656c6167, prev_in_context = 0x6172656c61672f62, m_type = 1668444975, m_duration = 1885696559, m_ctx = 0x5f726f746163696c, m_lock = 0x7070632e6d6d73, m_psi = 0x0}Is it so that RELEASE_ALL_LOCKS() does implicit commit and we should somehow release all MDL-locks also?