Uploaded image for project: 'MariaDB Server'
  1. MariaDB Server
  2. MDEV-14833

[Draft] Failing assertion: trx->error_state == DB_SUCCESS in file /home/travis/src/storage/innobase/que/que0que.cc line 1214

    XMLWordPrintable

Details

    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.

      Attachments

        Activity

          People

            elenst Elena Stepanova
            elenst Elena Stepanova
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              Git Integration

                Error rendering 'com.xiplink.jira.git.jira_git_plugin:git-issue-webpanel'. Please contact your Jira administrators.