[MDEV-24672] Assertion `!is_set() || (m_status == DA_OK_BULK && is_bulk_op())' failed in Diagnostics_area::set_ok_status on CREATE TABLE Created: 2021-01-25  Updated: 2023-04-27

Status: Open
Project: MariaDB Server
Component/s: Replication
Affects Version/s: 10.2, 10.3, 10.4, 10.5, 10.6
Fix Version/s: 10.4, 10.5, 10.6

Type: Bug Priority: Major
Reporter: Roel Van de Paar Assignee: Rucha Deodhar
Resolution: Unresolved Votes: 0
Labels: None


 Description   

There are a number of past bugs with this assert, but they are either closed or the stack is different. Bug is seen a lot in testing.

# mysqld options required for replay: --log-bin
 
SET sql_mode='';
 
SET GLOBAL max_binlog_size=2048;
 
--error 1186
 
RESET MASTER TO 2147483647;
 
CREATE TABLE t1 (c INT);
 
CREATE TEMPORARY TABLE t2 ENGINE=InnoDB AS SELECT * FROM performance_schema.file_summary_by_event_name;
 
CREATE TABLE t3 (a INT, b BINARY (70), c VARCHAR(70), d VARBINARY (70), e VARBINARY (70)) ENGINE=InnoDB;
 
INSERT INTO t3 VALUES (1111111111111111111,'aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa','aaaaaaaaaaaaaaaaaaaaaaaaa','aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa','a');
 
INSERT INTO t1 VALUES (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL);
 
DROP TABLE t1;
 
CREATE TABLE t4 (c CHAR(70)) ENGINE=InnoDB;
 
CREATE TABLE t5 SELECT * FROM t4;

Leads to:

10.6.0 9118fd360a3da0bba521caf2a35c424968235ac4 (Debug)

 
mysqld: /test/10.6_dbg/sql/sql_error.cc:334: void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*): Assertion `!is_set() || (m_status == DA_OK_BULK && is_bulk_op())' failed.

10.6.0 9118fd360a3da0bba521caf2a35c424968235ac4 (Debug)

 
Core was generated by `/test/MD010121-mariadb-10.6.0-linux-x86_64-dbg/bin/mysqld --no-defaults --core-'.
 
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 0x150fa01f9700 (LWP 289139))]
 
(gdb) bt
 
#0  __pthread_kill (threadid=<optimized out>, signo=signo@entry=6) at ../sysdeps/unix/sysv/linux/pthread_kill.c:56
 
#1  0x000055d95b8720d7 in my_write_core (sig=sig@entry=6) at /test/10.6_dbg/mysys/stacktrace.c:424
 
#2  0x000055d95b006ab1 in handle_fatal_signal (sig=6) at /test/10.6_dbg/sql/signal_handler.cc:330
 
#3  <signal handler called>
 
#4  __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
 
#5  0x0000150fb50c2859 in __GI_abort () at abort.c:79
 
#6  0x0000150fb50c2729 in __assert_fail_base (fmt=0x150fb5258588 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=0x55d95b9d2df0 "!is_set() || (m_status == DA_OK_BULK && is_bulk_op())", file=0x55d95b9d2cd0 "/test/10.6_dbg/sql/sql_error.cc", line=334, function=<optimized out>) at assert.c:92
 
#7  0x0000150fb50d3f36 in __GI___assert_fail (assertion=assertion@entry=0x55d95b9d2df0 "!is_set() || (m_status == DA_OK_BULK && is_bulk_op())", file=file@entry=0x55d95b9d2cd0 "/test/10.6_dbg/sql/sql_error.cc", line=line@entry=334, function=function@entry=0x55d95b9d2e28 "void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*)") at assert.c:101
 
#8  0x000055d95ace6c0b in Diagnostics_area::set_ok_status (this=0x150f54006a68, affected_rows=0, last_insert_id=0, message=message@entry=0x150fa01f76a0 "Records: 0  Duplicates: 0  Warnings: 2") at /test/10.6_dbg/sql/sql_error.h:1017
 
#9  0x000055d95acf6519 in my_ok (message=0x150fa01f76a0 "Records: 0  Duplicates: 0  Warnings: 2", id=<optimized out>, affected_rows_arg=<optimized out>, thd=<optimized out>) at /test/10.6_dbg/sql/sql_class.h:5294
 
#10 select_insert::send_ok_packet (this=this@entry=0x150f54014730) at /test/10.6_dbg/sql/sql_insert.cc:4238
 
#11 0x000055d95acf7d48 in select_create::send_eof (this=0x150f54014730) at /test/10.6_dbg/sql/sql_insert.cc:5009
 
#12 0x000055d95adc44b2 in do_select (procedure=<optimized out>, join=0x150f54014830) at /test/10.6_dbg/sql/sql_select.cc:20200
 
#13 JOIN::exec_inner (this=this@entry=0x150f54014830) at /test/10.6_dbg/sql/sql_select.cc:4472
 
#14 0x000055d95adc47ad in JOIN::exec (this=this@entry=0x150f54014830) at /test/10.6_dbg/sql/sql_select.cc:4252
 
#15 0x000055d95adc2a09 in mysql_select (thd=thd@entry=0x150f54000db8, tables=0x150f540135b0, fields=@0x150f54013110: {<base_list> = {<Sql_alloc> = {<No data fields>}, first = 0x150f54013568, last = 0x150f54013568, elements = 1}, <No data fields>}, conds=0x0, og_num=0, order=0x0, group=0x0, having=0x0, proc_param=0x0, select_options=2201171004160, result=0x150f54014730, unit=0x150f54004f80, select_lex=0x150f54012fc0) at /test/10.6_dbg/sql/sql_select.cc:4668
 
#16 0x000055d95adc2cd0 in handle_select (thd=thd@entry=0x150f54000db8, lex=lex@entry=0x150f54004eb8, result=result@entry=0x150f54014730, setup_tables_done_option=setup_tables_done_option@entry=0) at /test/10.6_dbg/sql/sql_select.cc:417
 
#17 0x000055d95ae1987a in Sql_cmd_create_table_like::execute (this=0x150f54012888, thd=0x150f54000db8) at /test/10.6_dbg/sql/sql_table.cc:12073
 
#18 0x000055d95ad474c6 in mysql_execute_command (thd=thd@entry=0x150f54000db8) at /test/10.6_dbg/sql/sql_parse.cc:5858
 
#19 0x000055d95ad2e072 in mysql_parse (thd=thd@entry=0x150f54000db8, rawbuf=<optimized out>, length=<optimized out>, parser_state=parser_state@entry=0x150fa01f83d0) at /test/10.6_dbg/sql/sql_parse.cc:7881
 
#20 0x000055d95ad3c1ec in dispatch_command (command=command@entry=COM_QUERY, thd=thd@entry=0x150f54000db8, packet=packet@entry=0x150f54008e59 "CREATE TABLE t5 SELECT * FROM t4", packet_length=packet_length@entry=32) at /test/10.6_dbg/sql/sql_class.h:1293
 
#21 0x000055d95ad3f52d in do_command (thd=0x150f54000db8) at /test/10.6_dbg/sql/sql_parse.cc:1348
 
#22 0x000055d95ae9b7fc in do_handle_one_connection (connect=<optimized out>, connect@entry=0x55d95e931e08, put_in_cache=put_in_cache@entry=true) at /test/10.6_dbg/sql/sql_connect.cc:1410
 
#23 0x000055d95ae9bf03 in handle_one_connection (arg=arg@entry=0x55d95e931e08) at /test/10.6_dbg/sql/sql_connect.cc:1312
 
#24 0x000055d95b35188f in pfs_spawn_thread (arg=0x55d95e8a3fe8) at /test/10.6_dbg/storage/perfschema/pfs.cc:2201
 
#25 0x0000150fb55d0609 in start_thread (arg=<optimized out>) at pthread_create.c:477
 
#26 0x0000150fb51bf293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95

Bug confirmed present in:
MariaDB: 10.2.37 (dbg), 10.3.28 (dbg), 10.4.18 (dbg), 10.5.9 (dbg), 10.6.0 (dbg)

Bug (or feature/syntax) confirmed not present in:
MariaDB: 10.2.37 (opt), 10.3.28 (opt), 10.4.18 (opt), 10.5.9 (opt), 10.6.0 (opt)
MySQL: 5.5.62 (dbg), 5.5.62 (opt), 5.6.50 (dbg), 5.6.50 (opt), 5.7.32 (dbg), 5.7.32 (opt), 8.0.22 (dbg), 8.0.22 (opt)

 Comments   
Comment by Roel Van de Paar [ 2021-01-25 ]

Thank you Elkin. I do not see the error on RESET MASTER, and 10.2.37 (build 1 Jan 21) seems to crash readily;

...
 
10.2.37>RESET MASTER TO 2147483647;
 
Query OK, 0 rows affected (0.02 sec)
 
...
 
10.2.37>CREATE TABLE t5 SELECT * FROM t4;
 
ERROR 2013 (HY000): Lost connection to MySQL server during query
 
...

Could it be that you need to add --log-bin startup? i.e. your error is ER_FLUSH_MASTER_BINLOG_CLOSED?

Comment by Elena Stepanova [ 2021-01-25 ]

I can reproduce it easily on current 10.2 and 10.5 debug.
Here is a self-contained MTR version which solves ER_FLUSH_MASTER_BINLOG_CLOSED problem. The actual expected error is ER_NO_UNIQUE_LOGFILE for the last statement.
The test case would also need major clean up and suppressions before it could be added to the regression suite.

--source include/have_log_bin.inc
 
 
 
SET sql_mode='';
 
SET GLOBAL max_binlog_size=2048;
 
RESET MASTER TO 2147483647;
 
CREATE TABLE t1 (c INT);
 
CREATE TEMPORARY TABLE t2 ENGINE=InnoDB AS SELECT * FROM performance_schema.file_summary_by_event_name;
 
CREATE TABLE t3 (a INT, b BINARY (70), c VARCHAR(70), d VARBINARY (70), e VARBINARY (70)) ENGINE=InnoDB;
 
INSERT INTO t3 VALUES (1111111111111111111,'aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa','aaaaaaaaaaaaaaaaaaaaaaaaa','aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa','a');
 
INSERT INTO t1 VALUES (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL), (NULL);
 
DROP TABLE t1;
 
CREATE TABLE t4 (c CHAR(70)) ENGINE=InnoDB;
 
--error ER_NO_UNIQUE_LOGFILE
 
CREATE TABLE t5 SELECT * FROM t4;

Comment by Elena Stepanova [ 2021-01-25 ]

Somewhat less messy version of the test case:

--source include/have_binlog_format_row.inc
 
 
 
SET GLOBAL max_binlog_size=4096;
 
RESET MASTER TO 2147483647;
 
--error ER_NO_UNIQUE_LOGFILE
 
CREATE TABLE t1 SELECT REPEAT('a',4097) AS x;

Cleanup for max_binlog_size and suppressions for errors/warnings are still needed though.

Comment by Roel Van de Paar [ 2021-01-25 ]

Thank you elenst!

Comment by Roel Van de Paar [ 2021-01-25 ]

Testcases by Elena both crash at CLI also. Second one requires --log-bin --binlog-format=row at CLI as indicated. Thanks

Generated at Thu Feb 08 09:31:47 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.