[MDEV-14833] [Draft] Failing assertion: trx->error_state == DB_SUCCESS in file /home/travis/src/storage/innobase/que/que0que.cc line 1214 Created: 2017-12-31  Updated: 2023-09-24  Resolved: 2023-09-24

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

Type: Bug Priority: Major
Reporter: Elena Stepanova Assignee: Elena Stepanova
Resolution: Cannot Reproduce Votes: 0
Labels: None


 Description   

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

10.3 b2a102fb584e55a5c07c25a1caa9575fe1732c14

Version: '10.3.4-MariaDB-debug-log'  socket: '/home/travis/logs/vardir1_3/mysql.sock'  port: 19300  Source distribution
2017-12-30 20:53:26 21 [Warning] InnoDB: Unable to delete statistics for table test.t8: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = 't8'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = 't8';
2017-12-30 20:53:42 17 [Warning] InnoDB: Unable to delete statistics for table test.#mysql50##sql-ib186-257223246: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib186-257223246'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib186-257223246';
2017-12-30 20:53:42 20 [Warning] InnoDB: Unable to delete statistics for table test.#mysql50##sql-ib191-257223248: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib191-257223248'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib191-257223248';
2017-12-30 20:54:20 19 [ERROR] InnoDB: Cannot save table statistics for table `test`.`t11`: Operation interrupted
2017-12-30 20:54:23 21 [Warning] InnoDB: Unable to delete statistics for table test.t3: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = 't3'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = 't3';
2017-12-30 20:54:25 20 [Warning] InnoDB: Deleting persistent statistics for table `test`.`t15` failed: Unable to delete statistics for table test.t15: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = 't15'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = 't15';
2017-12-30 20:55:07 19 [ERROR] InnoDB: Cannot save table statistics for table `test`.`t3`: Operation interrupted
2017-12-30 20:55:15 20 [Warning] InnoDB: Unable to delete statistics for table test.#mysql50##sql-ib584-257223398: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib584-257223398'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib584-257223398';
2017-12-30 20:55:49 19 [ERROR] InnoDB: Cannot save table statistics for table `test`.`#sql-1d91_13`: Operation interrupted
2017-12-30 20:55:54 18 [Warning] InnoDB: Unable to delete statistics for table test.#mysql50##sql-ib741-257223448: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib741-257223448'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = '#mysql50##sql-ib741-257223448';
2017-12-30 20:56:27 19 [ERROR] InnoDB: Cannot save table statistics for table `test`.`#sql-1d91_13`: Operation interrupted
2017-12-30 20:56:27 18 [ERROR] InnoDB: Unable to rename statistics from test.#mysql50##sql-1d91_12 to test.t12 in mysql.innodb_table_stats: Operation interrupted. They can be renamed later using UPDATE mysql.innodb_table_stats SET database_name = 'test', table_name = 't12' WHERE database_name = 'test' AND table_name = '#mysql50##sql-1d91_12';
2017-12-30 20:56:31 22 [Warning] InnoDB: Unable to delete statistics for table test.t2: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = 't2'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = 't2';
2017-12-30 20:56:34 18 [Warning] InnoDB: Unable to delete statistics for table test.FTS_0000000000000387_BEING_DELETED: Lock wait. They can be deleted later using  DELETE FROM mysql.innodb_index_stats WHERE database_name = 'test' AND table_name = 'FTS_0000000000000387_BEING_DELETED'; DELETE FROM mysql.innodb_table_stats WHERE database_name = 'test' AND table_name = 'FTS_0000000000000387_BEING_DELETED';
2017-12-30 20:56:34 0x7fe0acbfe700  InnoDB: Assertion failure in file /home/travis/src/storage/innobase/que/que0que.cc line 1214
InnoDB: Failing assertion: trx->error_state == DB_SUCCESS
 
Some pointers may be invalid and cause the dump to abort.
Query (0x7fe06401f708): TRUNCATE TABLE t12 /* QNO 2675 CON_ID 18 */
Connection ID (thread ID): 18
Status: NOT_KILLED

#5  0x00007fe0addfc79b in abort () from /lib/x86_64-linux-gnu/libc.so.6
#6  0x0000000000fef17f in ut_dbg_assertion_failed (expr=0x152df58 "trx->error_state == DB_SUCCESS", file=0x152dc18 "/home/travis/src/storage/innobase/que/que0que.cc", line=1214) at /home/travis/src/storage/innobase/ut/ut0dbg.cc:61
#7  0x0000000000eb8690 in que_eval_sql (info=0x7fe0640d0688, sql=0x7fe06411a628 "PROCEDURE DROP_TABLE_PROC () IS\nsys_foreign_id CHAR;\ntable_id CHAR;\nindex_id CHAR;\nforeign_id CHAR;\nspace_id INT;\nfound INT;\nDECLARE CURSOR cur_fk IS\nSELECT ID FROM SYS_FOREIGN\nWHERE FOR_NAME = :table"..., reserve_dict_mutex=0, trx=0x7fe0ad0c6938) at /home/travis/src/storage/innobase/que/que0que.cc:1214
#8  0x0000000000f149d0 in row_drop_table_for_mysql (name=0x7fe0acbfb6a0 "test/FTS_", '0' <repeats 13 times>, "387_BEING_DELETED_CACHE", trx=0x7fe0ad0c6938, drop_db=true, create_failed=0, nonatomic=false) at /home/travis/src/storage/innobase/row/row0mysql.cc:3932
#9  0x000000000112acf3 in fts_drop_table (trx=0x7fe0ad0c6938, table_name=0x7fe0acbfb6a0 "test/FTS_", '0' <repeats 13 times>, "387_BEING_DELETED_CACHE") at /home/travis/src/storage/innobase/fts/fts0fts.cc:1486
#10 0x000000000112b333 in fts_drop_common_tables (trx=0x7fe0ad0c6938, fts_table=0x7fe0acbfb960) at /home/travis/src/storage/innobase/fts/fts0fts.cc:1625
#11 0x000000000112b60f in fts_drop_tables (trx=0x7fe0ad0c6938, table=0x7fe0640dbc58) at /home/travis/src/storage/innobase/fts/fts0fts.cc:1737
#12 0x0000000000f57f71 in row_truncate_update_system_tables (table=0x7fe0640dbc58, new_id=932, has_internal_doc_id=true, no_redo=false, trx=0x7fe0ad0c6938) at /home/travis/src/storage/innobase/row/row0trunc.cc:1543
#13 0x0000000000f5947e in row_truncate_table_for_mysql (table=0x7fe0640dbc58, trx=0x7fe0ad0c6938) at /home/travis/src/storage/innobase/row/row0trunc.cc:2080
#14 0x0000000000dc12e5 in ha_innobase::truncate (this=0x7fe0640d8068) at /home/travis/src/storage/innobase/handler/ha_innodb.cc:13298
#15 0x0000000000a7e8ad in handler::ha_truncate (this=0x7fe0640d8068) at /home/travis/src/sql/handler.cc:4231
#16 0x0000000000c579ae in Sql_cmd_truncate_table::handler_truncate (this=0x7fe06401fe28, thd=0x7fe06400b160, table_ref=0x7fe06401f810, is_tmp_table=false) at /home/travis/src/sql/sql_truncate.cc:246
#17 0x0000000000c58070 in Sql_cmd_truncate_table::truncate_table (this=0x7fe06401fe28, thd=0x7fe06400b160, table_ref=0x7fe06401f810) at /home/travis/src/sql/sql_truncate.cc:445
#18 0x0000000000c581e0 in Sql_cmd_truncate_table::execute (this=0x7fe06401fe28, thd=0x7fe06400b160) at /home/travis/src/sql/sql_truncate.cc:502
#19 0x00000000007a9757 in mysql_execute_command (thd=0x7fe06400b160) at /home/travis/src/sql/sql_parse.cc:6251
#20 0x00000000007ae357 in mysql_parse (thd=0x7fe06400b160, rawbuf=0x7fe06401f708 "TRUNCATE TABLE t12 /* QNO 2675 CON_ID 18 */", length=43, parser_state=0x7fe0acbfd490, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:7966
#21 0x000000000079ba2a in dispatch_command (command=COM_QUERY, thd=0x7fe06400b160, packet=0x7fe0640175d1 "TRUNCATE TABLE t12 /* QNO 2675 CON_ID 18 */ ", packet_length=44, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:1824
#22 0x000000000079a451 in do_command (thd=0x7fe06400b160) at /home/travis/src/sql/sql_parse.cc:1369
#23 0x00000000008f2553 in do_handle_one_connection (connect=0x36dba40) at /home/travis/src/sql/sql_connect.cc:1420
#24 0x00000000008f22d5 in handle_one_connection (arg=0x36dba40) at /home/travis/src/sql/sql_connect.cc:1326
#25 0x00007fe0ae99de9a in start_thread () from /lib/x86_64-linux-gnu/libpthread.so.0
#26 0x00007fe0adeb92ed in clone () from /lib/x86_64-linux-gnu/libc.so.6
#27 0x0000000000000000 in ?? ()

experimental 68ba6a4048fa9f40da10679a5702bdf419f76855

perl /home/travis/rqg/runall-new.pl --duration=350 --threads=6 --redefine=conf/mariadb/instant_add.yy --reporters=Backtrace,ErrorLog,Deadlock --engine=InnoDB -seed=1514667192 --basedir=/home/travis/server --mysqld=--max-statement-time=30 --mysqld=--loose-debug_assert_on_not_freed_memory=0 --grammar=conf/mariadb/optimizer.yy --gendata-advanced --skip-gendata --rpl-mode=mixed --vardir=/home/travis/logs/vardir1_3

Happens a lot in stress tests, highly sporadic.



 Comments   
Comment by Marko Mäkelä [ 2018-02-21 ]

Possibly related to MDEV-14511 which was reverted later due to too many issues.

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