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

Assertion in diagnostics area on DDL stats timeout

    XMLWordPrintable

Details

    • Can result in hang or crash
    • Q1/2026 Server Maintenance

    Description

      --source include/have_innodb.inc
       
      SET innodb_lock_wait_timeout = 1;
      CREATE TABLE t ENGINE=InnoDB AS SELECT * FROM mysql.innodb_table_stats;
       
      DROP TABLE t;
      

      10.6 7fed0143081988e9fbaf01c9e07aa261ec1f06be

      mariadbd: /data/bld/10.6-asan-ubsan/sql/sql_error.cc:347: void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*): Assertion `!is_set() || (m_status == DA_OK_BULK && is_bulk_op())' failed.
      260126 18:26:39 [ERROR] /share8t/bld/10.6-asan-ubsan/sql/mariadbd got signal 6 ;
       
      #9  0x00007fa761a45395 in __assert_fail_base (fmt=0x7fa761bb9a90 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=assertion@entry=0x56136432df40 "!is_set() || (m_status == DA_OK_BULK && is_bulk_op())", file=file@entry=0x56136432ef00 "/data/bld/10.6-asan-ubsan/sql/sql_error.cc", line=line@entry=347, function=function@entry=0x56136432dfa0 "void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*)") at ./assert/assert.c:92
      #10 0x00007fa761a53eb2 in __GI___assert_fail (assertion=0x56136432df40 "!is_set() || (m_status == DA_OK_BULK && is_bulk_op())", file=0x56136432ef00 "/data/bld/10.6-asan-ubsan/sql/sql_error.cc", line=347, function=0x56136432dfa0 "void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*)") at ./assert/assert.c:101
      #11 0x00005613609211f3 in Diagnostics_area::set_ok_status (this=this@entry=0x62b0000c31f8, affected_rows=affected_rows@entry=1, last_insert_id=last_insert_id@entry=0, message=message@entry=0x7fa752329bb0 "Records: 1  Duplicates: 0  Warnings: 1") at /data/bld/10.6-asan-ubsan/sql/sql_error.cc:347
      #12 0x000056136077cc52 in my_ok (thd=<optimized out>, affected_rows_arg=affected_rows_arg@entry=1, id=id@entry=0, message=message@entry=0x7fa752329bb0 "Records: 1  Duplicates: 0  Warnings: 1") at /data/bld/10.6-asan-ubsan/sql/sql_class.h:5884
      #13 0x000056136097f20c in select_insert::send_ok_packet (this=this@entry=0x62d0000a25a0) at /data/bld/10.6-asan-ubsan/sql/sql_insert.cc:4475
      #14 0x0000561360984c50 in select_create::send_eof (this=0x62d0000a25a0) at /data/bld/10.6-asan-ubsan/sql/sql_insert.cc:5333
      #15 0x0000561360d38386 in do_select (join=join@entry=0x62d0000a26f0, procedure=<optimized out>) at /data/bld/10.6-asan-ubsan/sql/sql_select.cc:21977
      #16 0x0000561360df1a40 in JOIN::exec_inner (this=this@entry=0x62d0000a26f0) at /data/bld/10.6-asan-ubsan/sql/sql_select.cc:4939
      #17 0x0000561360df2172 in JOIN::exec (this=this@entry=0x62d0000a26f0) at /data/bld/10.6-asan-ubsan/sql/sql_select.cc:4717
      #18 0x0000561360de8b00 in mysql_select (thd=thd@entry=0x62b0000bd218, tables=<optimized out>, fields=..., conds=conds@entry=0x0, og_num=og_num@entry=0, order=order@entry=0x0, group=<optimized out>, having=<optimized out>, proc_param=<optimized out>, select_options=<optimized out>, result=<optimized out>, unit=<optimized out>, select_lex=<optimized out>) at /data/bld/10.6-asan-ubsan/sql/sql_select.cc:5196
      #19 0x0000561360de9cea in handle_select (thd=thd@entry=0x62b0000bd218, lex=lex@entry=0x62b0000c1538, result=result@entry=0x62d0000a25a0, setup_tables_done_option=setup_tables_done_option@entry=0) at /data/bld/10.6-asan-ubsan/sql/sql_select.cc:573
      #20 0x0000561360fdc416 in Sql_cmd_create_table_like::execute (this=<optimized out>, thd=0x62b0000bd218) at /data/bld/10.6-asan-ubsan/sql/sql_table.cc:12451
      #21 0x0000561360b3f720 in mysql_execute_command (thd=thd@entry=0x62b0000bd218, is_called_from_prepared_stmt=is_called_from_prepared_stmt@entry=false) at /data/bld/10.6-asan-ubsan/sql/sql_parse.cc:6168
      #22 0x0000561360b43c8c in mysql_parse (thd=thd@entry=0x62b0000bd218, rawbuf=<optimized out>, length=<optimized out>, parser_state=parser_state@entry=0x7fa75232bac0) at /data/bld/10.6-asan-ubsan/sql/sql_parse.cc:8201
      #23 0x0000561360b4cf89 in dispatch_command (command=command@entry=COM_QUERY, thd=thd@entry=0x62b0000bd218, packet=packet@entry=0x629000276219 "CREATE TABLE t ENGINE=InnoDB AS SELECT * FROM mysql.innodb_table_stats", packet_length=packet_length@entry=70, blocking=blocking@entry=true) at /data/bld/10.6-asan-ubsan/sql/sql_parse.cc:1911
      #24 0x0000561360b59ca4 in do_command (thd=thd@entry=0x62b0000bd218, blocking=blocking@entry=true) at /data/bld/10.6-asan-ubsan/sql/sql_parse.cc:1421
      #25 0x00005613612dcc3c in do_handle_one_connection (connect=<optimized out>, connect@entry=0x608000018938, put_in_cache=put_in_cache@entry=true) at /data/bld/10.6-asan-ubsan/sql/sql_connect.cc:1386
      #26 0x00005613612ddd99 in handle_one_connection (arg=0x608000018938) at /data/bld/10.6-asan-ubsan/sql/sql_connect.cc:1298
      #27 0x0000561362d0f975 in pfs_spawn_thread (arg=0x617000007e98) at /data/bld/10.6-asan-ubsan/storage/perfschema/pfs.cc:2201
      #28 0x00007fa761aa81c4 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
      #29 0x00007fa761b2885c in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
      

      The failure started happening after this commit in 10.6.24:

      commit 687b18648ca41aecb4211f14f6506410a9495f9f
      Author: Thirunarayanan Balathandayuthapani <thiru@mariadb.com>
      Date:   Mon Sep 22 17:39:47 2025 +0530
       
          MDEV-35163  InnoDB persistent statistics fail to update after ALTER TABLE...ALGORITHM=COPY
      

      The non-debug failure is

      mysqltest: At line 4: query 'CREATE TABLE t ENGINE=InnoDB AS SELECT * FROM mysql.innodb_table_stats' failed: ER_ALTER_INFO (1088): Error updating stats for table after table rebuild: Lock wait timeout
      

      Before the guilty commit the CREATE would succeed.


      Original description (please check that this is fixed also)

      The test case is non-deterministic, run with --repeat=N. It usually fails for me in 5-10 attempts.

      --source include/have_innodb.inc
       
      CREATE TABLE t1 (a INT) ENGINE=InnoDB;
      CREATE TABLE t2 (pk INT AUTO_INCREMENT PRIMARY KEY) ENGINE=InnoDB;
       
      --connect (con1,localhost,root,,)
      --send
        ALTER TABLE t1 NOWAIT FORCE, ALGORITHM=COPY;
      --connection default
      ALTER TABLE t2 AUTO_INCREMENT 10, ALGORITHM=COPY, LOCK=NONE;
       
      --connection con1
      --error 0,ER_LOCK_WAIT_TIMEOUT
      --reap
       
      DROP TABLE t1, t2;
      

      11.4 3009210e63a8168afb0d946397133eb4bc16a5e4

      mariadbd: /data/bld/11.4-asan-ubsan/sql/sql_error.cc:346: void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*): Assertion `!is_set() || (m_status == DA_OK_BULK && is_bulk_op())' failed.
      260126 13:32:13 [ERROR] /share8t/bld/11.4-asan-ubsan/sql/mariadbd got signal 6 ;
       
      #9  0x00007fa0cbc45395 in __assert_fail_base (fmt=0x7fa0cbdb9a90 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=assertion@entry=0x5594cd595320 "!is_set() || (m_status == DA_OK_BULK && is_bulk_op())", file=file@entry=0x5594cd596460 "/data/bld/11.4-asan-ubsan/sql/sql_error.cc", line=line@entry=346, function=function@entry=0x5594cd595380 "void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*)") at ./assert/assert.c:92
      #10 0x00007fa0cbc53eb2 in __GI___assert_fail (assertion=0x5594cd595320 "!is_set() || (m_status == DA_OK_BULK && is_bulk_op())", file=0x5594cd596460 "/data/bld/11.4-asan-ubsan/sql/sql_error.cc", line=346, function=0x5594cd595380 "void Diagnostics_area::set_ok_status(ulonglong, ulonglong, const char*)") at ./assert/assert.c:101
      #11 0x00005594c994232d in Diagnostics_area::set_ok_status (this=this@entry=0x62c000226390, affected_rows=affected_rows@entry=0, last_insert_id=last_insert_id@entry=0, message=message@entry=0x7fa0b9b19980 "Records: 0  Duplicates: 0  Warnings: 1") at /data/bld/11.4-asan-ubsan/sql/sql_error.cc:346
      #12 0x00005594c9767610 in my_ok (thd=thd@entry=0x62c000220218, affected_rows_arg=0, id=id@entry=0, message=message@entry=0x7fa0b9b19980 "Records: 0  Duplicates: 0  Warnings: 1") at /data/bld/11.4-asan-ubsan/sql/sql_class.h:6059
      #13 0x00005594c98addbd in THD::my_ok_with_recreate_info (this=this@entry=0x62c000220218, info=..., warn_count=<optimized out>) at /data/bld/11.4-asan-ubsan/sql/sql_class.cc:8667
      #14 0x00005594ca0c0090 in mysql_alter_table (thd=thd@entry=0x62c000220218, new_db=<optimized out>, new_name=new_name@entry=0x62c000225420, create_info=create_info@entry=0x7fa0b9b1cd20, table_list=table_list@entry=0x62d0002f8748, recreate_info=recreate_info@entry=0x7fa0b9b1cab0, alter_info=<optimized out>, order_num=<optimized out>, order=<optimized out>, ignore=<optimized out>, if_exists=<optimized out>) at /data/bld/11.4-asan-ubsan/sql/sql_table.cc:12154
      #15 0x00005594ca3db6c8 in Sql_cmd_alter_table::execute (this=<optimized out>, thd=0x62c000220218) at /data/bld/11.4-asan-ubsan/sql/sql_alter.cc:701
      #16 0x00005594c9b6765b in mysql_execute_command (thd=thd@entry=0x62c000220218, is_called_from_prepared_stmt=is_called_from_prepared_stmt@entry=false) at /data/bld/11.4-asan-ubsan/sql/sql_parse.cc:5916
      #17 0x00005594c9b6c271 in mysql_parse (thd=thd@entry=0x62c000220218, rawbuf=<optimized out>, length=<optimized out>, parser_state=parser_state@entry=0x7fa0b9b1eab0) at /data/bld/11.4-asan-ubsan/sql/sql_parse.cc:7945
      #18 0x00005594c9b75661 in dispatch_command (command=command@entry=COM_QUERY, thd=thd@entry=0x62c000220218, packet=packet@entry=0x6290002d0219 "", packet_length=packet_length@entry=43, blocking=blocking@entry=true) at /data/bld/11.4-asan-ubsan/sql/sql_parse.cc:1923
      #19 0x00005594c9b8236a in do_command (thd=thd@entry=0x62c000220218, blocking=blocking@entry=true) at /data/bld/11.4-asan-ubsan/sql/sql_parse.cc:1433
      #20 0x00005594ca3ab02a in do_handle_one_connection (connect=<optimized out>, connect@entry=0x6080000090b8, put_in_cache=put_in_cache@entry=true) at /data/bld/11.4-asan-ubsan/sql/sql_connect.cc:1497
      #21 0x00005594ca3ac187 in handle_one_connection (arg=0x6080000090b8) at /data/bld/11.4-asan-ubsan/sql/sql_connect.cc:1409
      #22 0x00005594cbdfc2d9 in pfs_spawn_thread (arg=0x617000008218) at /data/bld/11.4-asan-ubsan/storage/perfschema/pfs.cc:2201
      #23 0x00007fa0cbca81c4 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
      #24 0x00007fa0cbd2885c in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
       
      Connection ID (thread ID): 5
      Status: NOT_KILLED
      Query (0x62d0002f8438): ALTER TABLE t1 NOWAIT FORCE, ALGORITHM=COPY
      

      The underlying (non-debug) error is this:

      11.4 3009210e63a8168afb0d946397133eb4bc16a5e4

      mysqltest: At line 14: query 'reap' failed with wrong errno ER_ALTER_INFO (1088): 'Error updating stats for table after table rebuild: Lock wait timeout', instead of  (0)...
      

      The failure started happening after this merge in 11.4.9:

      commit e8ef8c005545f0163d76077e285c700e2822f533
      Merge: d098e615422 990b44495c6
      Author: Marko Mäkelä
      Date:   Wed Sep 24 13:40:09 2025 +0300
       
          Merge 10.11 into 11.4
      

      I guess after this commit

      commit 687b18648ca41aecb4211f14f6506410a9495f9f
      Author: Thirunarayanan Balathandayuthapani
      Date:   Mon Sep 22 17:39:47 2025 +0530
       
          MDEV-35163  InnoDB persistent statistics fail to update after ALTER TABLE...ALGORITHM=COPY
      

      although, since it doesn't revert smoothly on 11.4, I couldn't check it for sure.

      Attachments

        Issue Links

          Activity

            People

              thiru Thirunarayanan Balathandayuthapani
              elenst Elena Stepanova
              Votes:
              0 Vote for this issue
              Watchers:
              2 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.