pluto:/data/Results/1626787333/TBR-1123-MDEV-25984/dev/shm/vardir/1626787333/94/1/rr

# 2021-07-20T14:39:29 [2351929] | [rr 2358553 355791]mysqld: /data/Server/10.6Y/storage/innobase/fts/fts0fts.cc:4784: doc_id_t fts_init_doc_id(const dict_table_t*): Assertion `max_doc_id > 0' failed.

# 2021-07-20T14:39:29 [2351929] | Thread 3 (Thread 2358553.2362740):

# 2021-07-20T14:39:29 [2351929] | #0  __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50

# 2021-07-20T14:39:29 [2351929] | #1  0x00007eff5f49e859 in __GI_abort () at abort.c:79

# 2021-07-20T14:39:29 [2351929] | #2  0x00007eff5f49e729 in __assert_fail_base (fmt=0x7eff5f634588 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=0x55af4aa36b40 "max_doc_id > 0", file=0x55af4aa32380 "/data/Server/10.6Y/storage/innobase/fts/fts0fts.cc", line=4784, function=<optimized out>) at assert.c:92

# 2021-07-20T14:39:29 [2351929] | #3  0x00007eff5f4aff36 in __GI___assert_fail (assertion=0x55af4aa36b40 "max_doc_id > 0", file=0x55af4aa32380 "/data/Server/10.6Y/storage/innobase/fts/fts0fts.cc", line=4784, function=0x55af4aa36b80 "doc_id_t fts_init_doc_id(const dict_table_t*)") at assert.c:101

# 2021-07-20T14:39:29 [2351929] | #4  0x000055af499dfd3a in fts_init_doc_id (table=0x6180001ac108) at /data/Server/10.6Y/storage/innobase/fts/fts0fts.cc:4784

# 2021-07-20T14:39:29 [2351929] | #5  0x000055af499d24b0 in fts_get_next_doc_id (table=0x6180001ac108, doc_id=0x7eff3a47bfb0) at /data/Server/10.6Y/storage/innobase/fts/fts0fts.cc:2520

# 2021-07-20T14:39:29 [2351929] | #6  0x000055af4961d724 in row_mysql_convert_row_to_innobase (row=0x6210000cdb88, prebuilt=0x6210000cd188, mysql_rec=0x61a000672cb8 "d\377\r\f", blob_heap=0x7eff3a47c0f0) at /data/Server/10.6Y/storage/innobase/row/row0mysql.cc:621

# 2021-07-20T14:39:29 [2351929] | #7  0x000055af49622137 in row_insert_for_mysql (mysql_rec=0x61a000672cb8 "d\377\r\f", prebuilt=0x6210000cd188, ins_mode=ROW_INS_NORMAL) at /data/Server/10.6Y/storage/innobase/row/row0mysql.cc:1314

# 2021-07-20T14:39:29 [2351929] | #8  0x000055af492ab7e1 in ha_innobase::write_row (this=0x61d0003656b8, record=0x61a000672cb8 "d\377\r\f") at /data/Server/10.6Y/storage/innobase/handler/ha_innodb.cc:7781

# 2021-07-20T14:39:29 [2351929] | #9  0x000055af489d7bb8 in handler::ha_write_row (this=0x61d0003656b8, buf=0x61a000672cb8 "d\377\r\f") at /data/Server/10.6Y/sql/handler.cc:7500

# 2021-07-20T14:39:29 [2351929] | #10 0x000055af481362b1 in write_record (thd=0x62b0001ab218, table=0x6190007c3898, info=0x7eff3a47ce60, sink=0x0) at /data/Server/10.6Y/sql/sql_insert.cc:2135

# 2021-07-20T14:39:29 [2351929] | #11 0x000055af4812eb77 in mysql_insert (thd=0x62b0001ab218, table_list=0x62b0001b24d0, fields=..., values_list=..., update_fields=..., update_values=..., duplic=DUP_ERROR, ignore=false, result=0x0) at /data/Server/10.6Y/sql/sql_insert.cc:1123

# 2021-07-20T14:39:29 [2351929] | #12 0x000055af481eb5a5 in mysql_execute_command (thd=0x62b0001ab218, is_called_from_prepared_stmt=false) at /data/Server/10.6Y/sql/sql_parse.cc:4566

# 2021-07-20T14:39:29 [2351929] | #13 0x000055af4820291e in mysql_parse (thd=0x62b0001ab218, rawbuf=0x62b0001b2238 "INSERT INTO t4 (col1,col2, col_int, col_string, col_text) VALUES ( 3085, 3085, 3085, REPEAT(SUBSTR(CAST( 3085 AS CHAR),1,1), 10), REPEAT(SUBSTR(CAST( 3085 - 1 AS CHAR),1,1), @fill_amount) ) /* E_R Thread6 QNO 306 CON_ID 31 */", length=225, parser_state=0x7eff3a47db20) at /data/Server/10.6Y/sql/sql_parse.cc:8026

# 2021-07-20T14:39:29 [2351929] | #14 0x000055af481dabbd in dispatch_command (command=COM_QUERY, thd=0x62b0001ab218, packet=0x629000dc0219 " INSERT INTO t4 (col1,col2, col_int, col_string, col_text) VALUES ( 3085, 3085, 3085, REPEAT(SUBSTR(CAST( 3085 AS CHAR),1,1), 10), REPEAT(SUBSTR(CAST( 3085 - 1 AS CHAR),1,1), @fill_amount) ) /* E_R Thread6 QNO 306 CON_ID 31 */ ", packet_length=227, blocking=true) at /data/Server/10.6Y/sql/sql_parse.cc:1896

# 2021-07-20T14:39:29 [2351929] | #15 0x000055af481d7f95 in do_command (thd=0x62b0001ab218, blocking=true) at /data/Server/10.6Y/sql/sql_parse.cc:1404

# 2021-07-20T14:39:29 [2351929] | #16 0x000055af485d60fb in do_handle_one_connection (connect=0x608000003a38, put_in_cache=true) at /data/Server/10.6Y/sql/sql_connect.cc:1410

# 2021-07-20T14:39:29 [2351929] | #17 0x000055af485d5a58 in handle_one_connection (arg=0x6080000038b8) at /data/Server/10.6Y/sql/sql_connect.cc:1312

# 2021-07-20T14:39:29 [2351929] | #18 0x00007eff5f9c7609 in start_thread (arg=<optimized out>) at pthread_create.c:477

# 2021-07-20T14:39:29 [2351929] | #19 0x00007eff5f59b293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95

origin/10.6 ae62f115beea8cb7c532ef1a72c359548316df9c 2021-07-20T10:55:03+03:00

10.6.4

RQG

-----

git clone https://github.com/mleich1/rqg --branch experimental RQG

perl rqg.pl \

--grammar=conf/mariadb/table_stress_innodb.yy \

--gendata=conf/mariadb/table_stress.zz \

--gendata_sql=conf/mariadb/table_stress.sql \

--rpl_mode=mixed \                                     <== not required but set during the run above

--mysqld=--loose-innodb_lock_schedule_algorithm=fcfs \

--mysqld=--loose-idle_write_transaction_timeout=0 \

--mysqld=--loose-idle_transaction_timeout=0 \

--mysqld=--loose-idle_readonly_transaction_timeout=0 \

--mysqld=--connect_timeout=60 \

--mysqld=--interactive_timeout=28800 \

--mysqld=--slave_net_timeout=60 \

--mysqld=--net_read_timeout=30 \

--mysqld=--net_write_timeout=60 \

--mysqld=--loose-table_lock_wait_timeout=50 \

--mysqld=--wait_timeout=28800 \

--mysqld=--lock-wait-timeout=86400 \

--mysqld=--innodb-lock-wait-timeout=50 \

--no-mask \

--queries=10000000 \

--seed=random \

--reporters=Backtrace \

--reporters=ErrorLog \

--reporters=Deadlock1 \

--validators=None \

--mysqld=--log_output=none \

--mysqld=--log_bin_trust_function_creators=1 \

--mysqld=--loose-debug_assert_on_not_freed_memory=0 \

--engine=InnoDB \

--restart_timeout=240 \

--mysqld=--plugin-load-add=file_key_management.so \

--mysqld=--loose-file-key-management-filename=$RQG_HOME/conf/mariadb/encryption_keys.txt \

--duration=300 \

--mysqld=--loose-innodb_fatal_semaphore_wait_threshold=300 \

--mysqld=--loose-innodb-sync-debug \

--mysqld=--innodb_stats_persistent=on \

--mysqld=--innodb_adaptive_hash_index=on \

--mysqld=--loose-innodb_evict_tables_on_commit_debug=off \

--mysqld=--loose-max-statement-time=30 \

--threads=9 \

--mysqld=--innodb-use-native-aio=0 \

--rr=Extended \

--rr_options="--wait" \

--mysqld=--innodb_page_size=64K \

--mysqld=--innodb-buffer-pool-size=24M \

--no_mask \

<local settings>

error pattern

[ 'TBR-1123-MDEV-25984', 'mysqld: .{1,250}fts0fts.cc:.{1,10}: doc_id_t fts_init_doc_id\(const dict_table_t\*\): Assertion \`max_doc_id > 0\' failed.+RESULT: The RQG run ended with status STATUS_SERVER_CRASHED' ],

(rr) t 20

[Switching to thread 20 (Thread 2358553.2362480)]

#0  0x0000000070000002 in ?? ()

(rr) where

#0  0x0000000070000002 in ?? ()

#1  0x00007eff5fe8f766 in _raw_syscall () at /home/roc/rr/rr/src/preload/raw_syscall.S:120

#2  0x00007eff5fe8b04e in traced_raw_syscall (call=<optimized out>) at /home/roc/rr/rr/src/preload/syscallbuf.c:272

#3  0x00007eff5fe8e709 in sys_futex (call=0x7eff3b4affa0) at /home/roc/rr/rr/src/preload/syscallbuf.c:1596

#4  syscall_hook_internal (call=0x7eff3b4affa0) at /home/roc/rr/rr/src/preload/syscallbuf.c:3176

#5  syscall_hook (call=0x7eff3b4affa0) at /home/roc/rr/rr/src/preload/syscallbuf.c:3329

#6  0x00007eff5fe8ae50 in _syscall_hook_trampoline () at /home/roc/rr/rr/src/preload/syscall_hook.S:313

#7  0x00007eff5fe8aeaf in __morestack () at /home/roc/rr/rr/src/preload/syscall_hook.S:458

#8  0x00007eff5fe8aeb5 in _syscall_hook_trampoline_48_3d_01_f0_ff_ff () at /home/roc/rr/rr/src/preload/syscall_hook.S:464

#9  0x00007eff5f5948a3 in syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38

#10 0x000055af497038fc in ssux_lock_low::wait (this=0x55af4b889c00 <dict_sys+64>, lk=2147483650)

    at /data/Server/10.6Y/storage/innobase/sync/srw_lock.cc:227

#11 0x000055af49703625 in ssux_lock_low::wr_wait (this=0x55af4b889c00 <dict_sys+64>, lk=2147483650)

    at /data/Server/10.6Y/storage/innobase/sync/srw_lock.cc:275

#12 0x000055af492ed289 in ssux_lock_low::wr_lock (this=0x55af4b889c00 <dict_sys+64>)

    at /data/Server/10.6Y/storage/innobase/include/srw_lock.h:214

#13 0x000055af498d831c in dict_sys_t::lock (this=0x55af4b889bc0 <dict_sys>)

    at /data/Server/10.6Y/storage/innobase/dict/dict0dict.cc:1071

#14 0x000055af496284e9 in row_mysql_lock_data_dictionary_func (trx=0x7eff53758b48)

    at /data/Server/10.6Y/storage/innobase/row/row0mysql.cc:2226

#15 0x000055af49382ba3 in rollback_inplace_alter_table (ha_alter_info=0x7eff3b5abb50, table=0x619000456098, prebuilt=0x6210000aa188)

    at /data/Server/10.6Y/storage/innobase/handler/handler0alter.cc:8763

#16 0x000055af4935ee90 in ha_innobase::commit_inplace_alter_table (this=0x61d0003cbeb8, altered_table=0x7eff3b5ac250, 

    ha_alter_info=0x7eff3b5abb50, commit=false) at /data/Server/10.6Y/storage/innobase/handler/handler0alter.cc:10805

#17 0x000055af489c6fcc in handler::ha_commit_inplace_alter_table (this=0x61d0003cbeb8, altered_table=0x7eff3b5ac250, 

    ha_alter_info=0x7eff3b5abb50, commit=false) at /data/Server/10.6Y/sql/handler.cc:5177

#18 0x000055af484507db in mysql_inplace_alter_table (thd=0x62b00016c218, table_list=0x62b0001733f8, table=0x619000456098, 

    altered_table=0x7eff3b5ac250, ha_alter_info=0x7eff3b5abb50, target_mdl_request=0x7eff3b5abc50, ddl_log_state=0x7eff3b5ab970, 

    trigger_param=0x7eff3b5ac6a0, alter_ctx=0x7eff3b5ad120) at /data/Server/10.6Y/sql/sql_table.cc:7536

#19 0x000055af4846366e in mysql_alter_table (thd=0x62b00016c218, new_db=0x62b000170c28, new_name=0x62b000171040, 

    create_info=0x7eff3b5ae5d0, table_list=0x62b0001733f8, alter_info=0x7eff3b5ae4a0, order_num=0, order=0x0, ignore=false, 

    if_exists=false) at /data/Server/10.6Y/sql/sql_table.cc:10205

--Type <RET> for more, q to quit, c to continue without paging--

#20 0x000055af485efc1f in Sql_cmd_alter_table::execute (this=0x62b000173c40, thd=0x62b00016c218)

    at /data/Server/10.6Y/sql/sql_alter.cc:550

#21 0x000055af481f644c in mysql_execute_command (thd=0x62b00016c218, is_called_from_prepared_stmt=false)

    at /data/Server/10.6Y/sql/sql_parse.cc:5993

#22 0x000055af4820291e in mysql_parse (thd=0x62b00016c218, 

    rawbuf=0x62b000173238 "ALTER TABLE t4 ADD UNIQUE KEY IF NOT EXISTS `uidx1` ( col_text(9) ), LOCK = NONE  /* E_R Thread9 QNO 285 CON_ID 24 */", length=117, parser_state=0x7eff3b5afb20) at /data/Server/10.6Y/sql/sql_parse.cc:8026

#23 0x000055af481dabbd in dispatch_command (command=COM_QUERY, thd=0x62b00016c218, 

    packet=0x629000d20219 " ALTER TABLE t4 ADD UNIQUE KEY IF NOT EXISTS `uidx1` ( col_text(9) ), LOCK = NONE  /* E_R Thread9 QNO 285 CON_ID 24 */ ", packet_length=119, blocking=true) at /data/Server/10.6Y/sql/sql_parse.cc:1896

#24 0x000055af481d7f95 in do_command (thd=0x62b00016c218, blocking=true) at /data/Server/10.6Y/sql/sql_parse.cc:1404

#25 0x000055af485d60fb in do_handle_one_connection (connect=0x6080000036b8, put_in_cache=true)

    at /data/Server/10.6Y/sql/sql_connect.cc:1410

#26 0x000055af485d5a58 in handle_one_connection (arg=0x6080000036b8) at /data/Server/10.6Y/sql/sql_connect.cc:1312

#27 0x00007eff5f9c7609 in start_thread (arg=<optimized out>) at pthread_create.c:477

#28 0x00007eff5f59b293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95

(rr) where

#0  fts_init_doc_id (table=0x6180001ac108) at /data/Server/10.6Y/storage/innobase/fts/fts0fts.cc:4774

#1  0x000055af499d24b0 in fts_get_next_doc_id (table=0x6180001ac108, doc_id=0x7eff3a47bfb0)

    at /data/Server/10.6Y/storage/innobase/fts/fts0fts.cc:2520

#2  0x000055af4961d724 in row_mysql_convert_row_to_innobase (row=0x6210000cdb88, prebuilt=0x6210000cd188, 

    mysql_rec=0x61a000672cb8 "d\377\r\f", blob_heap=0x7eff3a47c0f0) at /data/Server/10.6Y/storage/innobase/row/row0mysql.cc:621

#3  0x000055af49622137 in row_insert_for_mysql (mysql_rec=0x61a000672cb8 "d\377\r\f", prebuilt=0x6210000cd188, ins_mode=ROW_INS_NORMAL)

    at /data/Server/10.6Y/storage/innobase/row/row0mysql.cc:1314

#4  0x000055af492ab7e1 in ha_innobase::write_row (this=0x61d0003656b8, record=0x61a000672cb8 "d\377\r\f")

    at /data/Server/10.6Y/storage/innobase/handler/ha_innodb.cc:7781

#5  0x000055af489d7bb8 in handler::ha_write_row (this=0x61d0003656b8, buf=0x61a000672cb8 "d\377\r\f")

    at /data/Server/10.6Y/sql/handler.cc:7500

#6  0x000055af481362b1 in write_record (thd=0x62b0001ab218, table=0x6190007c3898, info=0x7eff3a47ce60, sink=0x0)

    at /data/Server/10.6Y/sql/sql_insert.cc:2135

#7  0x000055af4812eb77 in mysql_insert (thd=0x62b0001ab218, table_list=0x62b0001b24d0, fields=..., values_list=..., update_fields=..., 

    update_values=..., duplic=DUP_ERROR, ignore=false, result=0x0) at /data/Server/10.6Y/sql/sql_insert.cc:1123

#8  0x000055af481eb5a5 in mysql_execute_command (thd=0x62b0001ab218, is_called_from_prepared_stmt=false)

    at /data/Server/10.6Y/sql/sql_parse.cc:4566

#9  0x000055af4820291e in mysql_parse (thd=0x62b0001ab218, 

    rawbuf=0x62b0001b2238 "INSERT INTO t4 (col1,col2, col_int, col_string, col_text) VALUES ( 3085, 3085, 3085, REPEAT(SUBSTR(CAST( 3085 AS CHAR),1,1), 10), REPEAT(SUBSTR(CAST( 3085 - 1 AS CHAR),1,1), @fill_amount) ) /* E_R Thr"..., length=225, 

    parser_state=0x7eff3a47db20) at /data/Server/10.6Y/sql/sql_parse.cc:8026

#10 0x000055af481dabbd in dispatch_command (command=COM_QUERY, thd=0x62b0001ab218, 

    packet=0x629000dc0219 " INSERT INTO t4 (col1,col2, col_int, col_string, col_text) VALUES ( 3085, 3085, 3085, REPEAT(SUBSTR(CAST( 3085 AS CHAR),1,1), 10), REPEAT(SUBSTR(CAST( 3085 - 1 AS CHAR),1,1), @fill_amount) ) /* E_R Th"..., packet_length=227, blocking=true)

    at /data/Server/10.6Y/sql/sql_parse.cc:1896

#11 0x000055af481d7f95 in do_command (thd=0x62b0001ab218, blocking=true) at /data/Server/10.6Y/sql/sql_parse.cc:1404

#12 0x000055af485d60fb in do_handle_one_connection (connect=0x608000003a38, put_in_cache=true)

    at /data/Server/10.6Y/sql/sql_connect.cc:1410

#13 0x000055af485d5a58 in handle_one_connection (arg=0x6080000038b8) at /data/Server/10.6Y/sql/sql_connect.cc:1312

#14 0x00007eff5f9c7609 in start_thread (arg=<optimized out>) at pthread_create.c:477

#15 0x00007eff5f59b293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95

commit 1bd681c8b3c5213ce1f7976940a7dc38b48a0d39

Author: Marko Mäkelä <marko.makela@mariadb.com>

Date:   Wed Jun 9 17:02:55 2021 +0300

    MDEV-25506 (3 of 3): Do not delete .ibd files before commit

    This is a complete rewrite of DROP TABLE, also as part of other DDL,

    such as ALTER TABLE, CREATE TABLE...SELECT, TRUNCATE TABLE.

diff --git a/storage/innobase/handler/handler0alter.cc b/storage/innobase/handler/handler0alter.cc

index f2a2ae7008b..9e9c0a17a39 100644

--- a/storage/innobase/handler/handler0alter.cc

+++ b/storage/innobase/handler/handler0alter.cc

@@ -9019,6 +9019,7 @@ inline bool rollback_inplace_alter_table(Alter_inplace_info *ha_alter_info,

         ut_a(!lock_table_for_trx(dict_sys.sys_fields, ctx->trx, LOCK_X));

       }

       innodb_lock_wait_timeout= save_timeout;

+      DEBUG_SYNC_C("innodb_rollback_after_fts_lock");

       row_mysql_lock_data_dictionary(ctx->trx);

       ctx->rollback_instant();

       innobase_rollback_sec_index(ctx->old_table, table,

--source include/have_innodb.inc

--source include/have_debug.inc

--source include/have_debug_sync.inc

create table t1(f1 char(100), f2 char(100), f3 int,

                fulltext(f2))engine=innodb;

insert into t1 values("mysql", "mariadb", 1);

insert into t1 values("innodb", "maria", 1);

alter table t1 drop index f2;

--source include/restart_mysqld.inc

SET DEBUG_SYNC='innodb_rollback_after_fts_lock SIGNAL insert_dml WAIT_FOR ddl_continue';

send alter table t1 add unique index(f3);

connect(con1,localhost,root,,,);

SET DEBUG_SYNC='now WAIT_FOR insert_dml';

INSERT INTO t1 VALUES("index", "index_1", 2);

SET DEBUG_SYNC='now SIGNAL ddl_continue';

connection default;

reap;

show create table t1;

drop table t1;