[MDEV-15329] [Draft] InnoDB: Assertion failure in dict_table_check_for_dup_indexes() Created: 2018-02-16  Updated: 2019-10-30  Resolved: 2019-10-30

Status: Closed
Project: MariaDB Server
Component/s: Storage Engine - InnoDB
Affects Version/s: 10.2, 10.3, 10.4
Fix Version/s: N/A

Type: Bug Priority: Major
Reporter: Elena Stepanova Assignee: Unassigned
Resolution: Duplicate Votes: 0
Labels: online-ddl, upstream

Attachments: HTML File threads    
Issue Links:
Duplicate
is duplicated by MDEV-18016 Assertion failure in dict_table_check... Closed
Relates
relates to MDEV-14825 Assertion `col->ord_part' in row_buil... Closed
relates to MDEV-18017 Assertion `!index->to_be_dropped' or ... Closed

 Description   

Note: quite possibly related to the "XA family"

https://api.travis-ci.org/v3/job/342149690/log.txt

10.3 990289a78fdddebdebcad6c675c2513602bdf26b

2018-02-16 04:36:45 0x7fc15c08f700  InnoDB: Assertion failure in file /home/travis/src/storage/innobase/dict/dict0dict.cc line 6424
InnoDB: We intentionally generate a memory trap.
InnoDB: Submit a detailed bug report to https://jira.mariadb.org/
InnoDB: If you get repeated assertion failures or crashes, even
InnoDB: immediately after the mysqld startup, there may be
InnoDB: corruption in the InnoDB tablespace. Please refer to
InnoDB: https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/
InnoDB: about forcing recovery.
180216  4:36:45 [ERROR] 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.
 
To report this bug, see https://mariadb.com/kb/en/reporting-bugs
 
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.
 
Server version: 10.3.5-MariaDB-debug-log
key_buffer_size=134217728
read_buffer_size=131072
max_used_connections=8
max_threads=153
thread_count=15
It is possible that mysqld could use up to 
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 467462 K  bytes of memory
Hope that's ok; if not, decrease some variables in the equation.
 
Thread pointer: 0x7fc0f4015c80
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 = 0x7fc15c08ee30 thread_stack 0x49000
/home/travis/server/bin/mysqld(my_print_stacktrace+0x40)[0x563ecc309c5d]
mysys/stacktrace.c:269(my_print_stacktrace)[0x563ecbb36a09]
/lib/x86_64-linux-gnu/libpthread.so.0(+0x10330)[0x7fc165b9e330]
linux/raise.c:56(__GI_raise)[0x7fc164fdbc37]
stdlib/abort.c:91(__GI_abort)[0x7fc164fdf028]
ut/ut0dbg.cc:62(__static_initialization_and_destruction_0)[0x563ecbf98528]
dict/dict0dict.cc:6432(dict_table_check_for_dup_indexes(dict_table_t const*, check_name))[0x563ecc05c074]
handler/handler0alter.cc:6162(ha_innobase::prepare_inplace_alter_table(TABLE*, Alter_inplace_info*))[0x563ecbda4bdc]
sql/handler.cc:4384(handler::ha_prepare_inplace_alter_table(TABLE*, Alter_inplace_info*))[0x563ecbb42b6d]
sql/ha_partition.cc:9958(ha_partition::prepare_inplace_alter_table(TABLE*, Alter_inplace_info*))[0x563ecc2d1f61]
sql/handler.cc:4384(handler::ha_prepare_inplace_alter_table(TABLE*, Alter_inplace_info*))[0x563ecbb42b6d]
sql/sql_table.cc:7589(mysql_inplace_alter_table)[0x563ecb920418]
sql/sql_table.cc:9812(mysql_alter_table(THD*, st_mysql_const_lex_string const*, st_mysql_const_lex_string const*, HA_CREATE_INFO*, TABLE_LIST*, Alter_info*, unsigned int, st_order*, bool))[0x563ecb92694f]
sql/sql_alter.cc:334(Sql_cmd_alter_table::execute(THD*))[0x563ecb9b1528]
sql/sql_parse.cc:6251(mysql_execute_command(THD*))[0x563ecb851857]
sql/sql_parse.cc:7980(mysql_parse(THD*, char*, unsigned int, Parser_state*, bool, bool))[0x563ecb8563de]
sql/sql_parse.cc:1827(dispatch_command(enum_server_command, THD*, char*, unsigned int, bool, bool))[0x563ecb843bd4]
sql/sql_parse.cc:1370(do_command(THD*))[0x563ecb842620]
sql/sql_connect.cc:1402(do_handle_one_connection(CONNECT*))[0x563ecb9abfb3]
sql/sql_connect.cc:1309(handle_one_connection)[0x563ecb9abd40]
perfschema/pfs.cc:1864(pfs_spawn_thread)[0x563ecc2a2cbd]
nptl/pthread_create.c:312(start_thread)[0x7fc165b96184]
x86_64/clone.S:113(clone)[0x7fc1650a303d]
 
Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0x7fc0f402c888): ALTER TABLE `CC` ADD KEY ( d ) /* QNO 1710 CON_ID 16 */
Connection ID (thread ID): 16
Status: NOT_KILLED
 
Optimizer switch: index_merge=on,index_merge_union=on,index_merge_sort_union=on,index_merge_intersection=on,index_merge_sort_intersection=off,engine_condition_pushdown=off,index_condition_pushdown=on,derived_merge=on,derived_with_keys=on,firstmatch=on,loosescan=on,materialization=on,in_to_exists=on,semijoin=on,partial_match_rowid_merge=on,partial_match_table_scan=on,subquery_cache=on,mrr=off,mrr_cost_based=off,mrr_sort_keys=off,outer_join_with_cache=on,semijoin_with_cache=on,join_cache_incremental=on,join_cache_hashed=on,join_cache_bka=on,optimize_join_buffer_size=off,table_elimination=on,extended_keys=on,exists_to_in=on,orderby_uses_equalities=on,condition_pushdown_for_derived=on,split_materialized=on

# 2018-02-16T04:37:11 [26073] #5  0x00007fc164fdf028 in __GI_abort () at abort.c:89
# 2018-02-16T04:37:11 [26073] #6  0x0000563ecbf98528 in ut_dbg_assertion_failed (expr=0x0, file=0x563ecc66ee68 "/home/travis/src/storage/innobase/dict/dict0dict.cc", line=6424) at /home/travis/src/storage/innobase/ut/ut0dbg.cc:61
# 2018-02-16T04:37:11 [26073] #7  0x0000563ecc05c074 in dict_table_check_for_dup_indexes (table=0x7fc0f4481c68, check=CHECK_ABORTED_OK) at /home/travis/src/storage/innobase/dict/dict0dict.cc:6424
# 2018-02-16T04:37:11 [26073] #8  0x0000563ecbda4bdc in ha_innobase::prepare_inplace_alter_table (this=0x7fc11c0e0218, altered_table=0x7fc0f40e0fd0, ha_alter_info=0x7fc15c08b1e0) at /home/travis/src/storage/innobase/handler/handler0alter.cc:6161
# 2018-02-16T04:37:11 [26073] #9  0x0000563ecbb42b6d in handler::ha_prepare_inplace_alter_table (this=0x7fc11c0e0218, altered_table=0x7fc0f40e0fd0, ha_alter_info=0x7fc15c08b1e0) at /home/travis/src/sql/handler.cc:4383
# 2018-02-16T04:37:11 [26073] #10 0x0000563ecc2d1f61 in ha_partition::prepare_inplace_alter_table (this=0x7fc11c0df968, altered_table=0x7fc0f40e0fd0, ha_alter_info=0x7fc15c08b1e0) at /home/travis/src/sql/ha_partition.cc:9958
# 2018-02-16T04:37:11 [26073] #11 0x0000563ecbb42b6d in handler::ha_prepare_inplace_alter_table (this=0x7fc11c0df968, altered_table=0x7fc0f40e0fd0, ha_alter_info=0x7fc15c08b1e0) at /home/travis/src/sql/handler.cc:4383
# 2018-02-16T04:37:11 [26073] #12 0x0000563ecb920418 in mysql_inplace_alter_table (thd=0x7fc0f4015c80, table_list=0x7fc0f402c9a8, table=0x7fc11c0a6660, altered_table=0x7fc0f40e0fd0, ha_alter_info=0x7fc15c08b1e0, inplace_supported=HA_ALTER_INPLACE_NO_LOCK_AFTER_PREPARE, target_mdl_request=0x7fc15c08b350, alter_ctx=0x7fc15c08bff0) at /home/travis/src/sql/sql_table.cc:7589
# 2018-02-16T04:37:11 [26073] #13 0x0000563ecb92694f in mysql_alter_table (thd=0x7fc0f4015c80, new_db=0x7fc0f401a218, new_name=0x7fc0f401a5c8, create_info=0x7fc15c08cbd0, table_list=0x7fc0f402c9a8, alter_info=0x7fc15c08cb20, order_num=0, order=0x0, ignore=false) at /home/travis/src/sql/sql_table.cc:9812
# 2018-02-16T04:37:11 [26073] #14 0x0000563ecb9b1528 in Sql_cmd_alter_table::execute (this=0x7fc0f402d0b0, thd=0x7fc0f4015c80) at /home/travis/src/sql/sql_alter.cc:334
# 2018-02-16T04:37:11 [26073] #15 0x0000563ecb851857 in mysql_execute_command (thd=0x7fc0f4015c80) at /home/travis/src/sql/sql_parse.cc:6251
# 2018-02-16T04:37:11 [26073] #16 0x0000563ecb8563de in mysql_parse (thd=0x7fc0f4015c80, rawbuf=0x7fc0f402c888 "ALTER TABLE `CC` ADD KEY ( d ) /* QNO 1710 CON_ID 16 */", length=55, parser_state=0x7fc15c08e5a0, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:7980
# 2018-02-16T04:37:11 [26073] #17 0x0000563ecb843bd4 in dispatch_command (command=COM_QUERY, thd=0x7fc0f4015c80, packet=0x7fc0f40d8611 "ALTER TABLE `CC` ADD KEY ( d ) /* QNO 1710 CON_ID 16 */ ", packet_length=56, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:1825
# 2018-02-16T04:37:11 [26073] #18 0x0000563ecb842620 in do_command (thd=0x7fc0f4015c80) at /home/travis/src/sql/sql_parse.cc:1370
# 2018-02-16T04:37:11 [26073] #19 0x0000563ecb9abfb3 in do_handle_one_connection (connect=0x563ecf70dfd0) at /home/travis/src/sql/sql_connect.cc:1402
# 2018-02-16T04:37:11 [26073] #20 0x0000563ecb9abd40 in handle_one_connection (arg=0x563ecf70dfd0) at /home/travis/src/sql/sql_connect.cc:1308
# 2018-02-16T04:37:11 [26073] #21 0x0000563ecc2a2cbd in pfs_spawn_thread (arg=0x563ecf720980) at /home/travis/src/storage/perfschema/pfs.cc:1862
# 2018-02-16T04:37:11 [26073] #22 0x00007fc165b96184 in start_thread (arg=0x7fc15c08f700) at pthread_create.c:312
# 2018-02-16T04:37:11 [26073] #23 0x00007fc1650a303d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111

experimental 6b97ede2ef98c5ef90c2387528266f7fcde1685d

perl /home/travis/rqg/runall-new.pl --duration=350 --threads=6 --seed=1518755696 --reporters=Backtrace,ErrorLog,Deadlock --validators=TransformerNoComparator --transformers=ExecuteAsCTE,ExecuteAsDeleteReturning,ExecuteAsExcept,ExecuteAsExecuteImmediate,ExecuteAsInsertSelect,ExecuteAsIntersect,ExecuteAsUnion,ExecuteAsUpdateDelete,ExecuteAsView,ExecuteAsPreparedTwice,ExecuteAsSPTwice --redefine=conf/mariadb/general-workarounds.yy --mysqld=--log_output=FILE --views --vcols --redefine=conf/mariadb/versioning.yy --redefine=conf/mariadb/alter_table.yy --redefine=conf/mariadb/bulk_insert.yy --redefine=conf/mariadb/sequences.yy --redefine=conf/mariadb/xa.yy --basedir=/home/travis/server --mysqld=--log_bin_trust_function_creators=1 --mysqld=--log-bin --mysqld=--max-statement-time=30 --mysqld=--loose-debug_assert_on_not_freed_memory=0 --grammar=conf/runtime/performance_schema.yy --mysqld=--performance-schema --engine=InnoDB --vardir=/home/travis/logs/vardir1_2

Not reproducible right away.



 Comments   
Comment by Elena Stepanova [ 2018-03-03 ]

New occurrence: https://api.travis-ci.org/v3/job/348503141/log.txt

Comment by Elena Stepanova [ 2018-03-09 ]

New occurrence on 10.2: https://api.travis-ci.org/v3/job/350884798/log.txt

Comment by Elena Stepanova [ 2018-03-19 ]

New occurrence on 10.3: https://api.travis-ci.org/v3/job/354590639/log.txt

Comment by Elena Stepanova [ 2018-03-29 ]

New occurrence on 10.3: https://api.travis-ci.org/v3/job/359590395/log.txt

Comment by Elena Stepanova [ 2018-04-06 ]

New occurrence on 10.3: https://api.travis-ci.org/v3/job/361924342/log.txt

Comment by Marko Mäkelä [ 2018-04-07 ]

I remember an Oracle internal bug report for the same, at least affecting 5.7, but likely 5.6 already.

Comment by Marko Mäkelä [ 2018-04-07 ]

This is possibly related to MDEV-14825 (dict_col_t::ord_part mismatch).

The upstream bug would regularly occur when the continuous integration system (PB2) ran a RQG test against MySQL 5.7. If I remember correctly, it involved ALTER TABLE on a partitioned table. Partitioning should merely improve the probability of this occurring; the issue should exist with non-partitioned tables.

Comment by Elena Stepanova [ 2019-10-30 ]

I can't remember why I didn't consider it a duplicate of MDEV-18016 (or vice versa) right away, only added a note about the relation to MDEV-18016. They look very much the same, closing.

Generated at Thu Feb 08 08:20:27 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.