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

gcol.innodb_virtual_debug sporadically failed in buildbot, row0upd.cc:2427: dberr_t row_upd_sec_index_entry(upd_node_t*, que_thr_t*): Assertion `0' failed

Details

    • 10.2.13

    Description

      http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10116/steps/test_2/logs/stdio
      http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10116/steps/test_1/logs/stdio

      gcol.innodb_virtual_debug 'innodb'       w2 [ fail ]
              Test ended at 2017-10-24 22:18:29
       
      CURRENT_TEST: gcol.innodb_virtual_debug
      mysqltest: At line 87: query 'DELETE FROM t WHERE a = 0' failed: 2013: Lost connection to MySQL server during query
       
      The result from queries just before the failure was:
      < snip >
      start transaction;
      update t set b=b+1;
      rollback;
      SET DEBUG_SYNC = 'now SIGNAL go_ahead';
      connection default;
      check table t;
      Table	Op	Msg_type	Msg_text
      test.t	check	status	OK
      SELECT c FROM t;
      c
      NULL
      3
      19
      29
      SET DEBUG_SYNC = 'innodb_inplace_alter_table_enter SIGNAL start_create WAIT_FOR go_ahead';
      ALTER TABLE t ADD COLUMN x2 INT;
      connection con1;
      SET DEBUG_SYNC = 'now WAIT_FOR start_create';
      start transaction;
      DELETE FROM t WHERE a = 0;
       
      More results from queries before failure can be found in /mnt/buildbot/build/mariadb-10.3.3/mysql-test/var/2/log/innodb_virtual_debug.log
       
       
      Server [mysqld.1 - pid: 22229, winpid: 22229, exit: 256] failed during test run
      Server log from this test:
      ----------SERVER LOG START-----------
      2017-10-24 22:18:28 44 [ERROR] InnoDB: Record in index `idx` of table `test`.`t` was not found on update: TUPLE (info_bits=0, 2 fields): {[4]    (0x00000000),[6]     b(0x000000000802)} at: COMPACT RECORD(info_bits=0, 2 fields): {NULL,[6]     e(0x000000000805)}
      2017-10-24 22:18:28 44 [Note] InnoDB: GIS MBR INFO: 6.32404e-322 and 3.23508e-316, -nan, -2.18566e-311
       
      mysqld: /home/buildbot/buildbot/build/mariadb-10.3.3/storage/innobase/row/row0upd.cc:2427: dberr_t row_upd_sec_index_entry(upd_node_t*, que_thr_t*): Assertion `0' failed.
      171024 22:18:28 [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.3-MariaDB-debug-log
      key_buffer_size=1048576
      read_buffer_size=131072
      max_used_connections=2
      max_threads=153
      thread_count=8
      It is possible that mysqld could use up to 
      key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 61908 K  bytes of memory
      Hope that's ok; if not, decrease some variables in the equation.
       
      Thread pointer: 0xabb08108
      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 = 0xacab82a0 thread_stack 0x49000
      /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld(my_print_stacktrace+0x3b)[0x8df3153]
      /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld(handle_fatal_signal+0x3cf)[0x863fb53]
      [0xb776b400]
      [0xb776b424]
      /lib/i386-linux-gnu/libc.so.6(gsignal+0x4f)[0xb72471ef]
      /lib/i386-linux-gnu/libc.so.6(abort+0x175)[0xb724a835]
      /lib/i386-linux-gnu/libc.so.6(+0x27095)[0xb7240095]
      /lib/i386-linux-gnu/libc.so.6(+0x27147)[0xb7240147]
      /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld[0x8b1bf85]
      /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld[0x8b1c6c1]
      /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld[0x8b1edd5]
      mysys/stacktrace.c:269(my_print_stacktrace)[0x8b1f122]
      row/row0upd.cc:2431(row_upd_sec_index_entry)[0x8aba8fc]
      handler/ha_innodb.cc:9245(ha_innobase::delete_row(unsigned char const*))[0x8966932]
      sql/handler.cc:6110(handler::ha_delete_row(unsigned char const*))[0x864fea7]
      sql/sql_delete.cc:637(mysql_delete(THD*, TABLE_LIST*, Item*, SQL_I_List<st_order>*, unsigned long long, unsigned long long, select_result*))[0x87dd5c7]
      sql/sql_parse.cc:4883(mysql_execute_command(THD*))[0x838f83a]
      sql/sql_parse.cc:7921(mysql_parse(THD*, char*, unsigned int, Parser_state*, bool, bool))[0x8399aa3]
      sql/sql_parse.cc:1821(dispatch_command(enum_server_command, THD*, char*, unsigned int, bool, bool))[0x83867ed]
      sql/sql_parse.cc:1370(do_command(THD*))[0x83852a7]
      sql/sql_connect.cc:1418(do_handle_one_connection(CONNECT*))[0x84cdb17]
      sql/sql_connect.cc:1325(handle_one_connection)[0x84cd8a5]
      perfschema/pfs.cc:1864(pfs_spawn_thread)[0x8862625]
      /lib/i386-linux-gnu/libpthread.so.0(+0x6d4c)[0xb74f3d4c]
      /lib/i386-linux-gnu/libc.so.6(clone+0x5e)[0xb7303ace]
       
      Trying to get some variables.
      Some pointers may be invalid and cause the dump to abort.
      Query (0xabb17d38): DELETE FROM t WHERE a = 0
      Connection ID (thread ID): 44
      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_grouping_derived=on
       
      The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
      information that should help you find out what is causing the crash.
      Writing a core file
      ----------SERVER LOG END-------------
      

      Attachments

        Issue Links

          Activity

            alice Alice Sherepa created issue -
            alice Alice Sherepa made changes -
            Field Original Value New Value
            Description http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10116/steps/test_1/logs/stdio
            {noformat}
            gcol.innodb_virtual_debug 'innodb' w4 [ fail ]
                    Test ended at 2017-10-24 21:25:52

            CURRENT_TEST: gcol.innodb_virtual_debug
            Warning: /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded: unknown variable 'loose-ssl-ca=/mnt/buildbot/build/mariadb-10.3.3/mysql-test/std_data/cacert.pem'
            Warning: /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded: unknown variable 'loose-ssl-cert=/mnt/buildbot/build/mariadb-10.3.3/mysql-test/std_data/client-cert.pem'
            Warning: /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded: unknown variable 'loose-ssl-key=/mnt/buildbot/build/mariadb-10.3.3/mysql-test/std_data/client-key.pem'
            Warning: /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded: unknown option '--loose-skip-ssl'
            mysqltest_embedded: /home/buildbot/buildbot/build/mariadb-10.3.3/storage/innobase/row/row0upd.cc:2427: dberr_t row_upd_sec_index_entry(upd_node_t*, que_thr_t*): Assertion `0' failed.
            mysqltest got signal 6
            read_command_buf (0x96a45c0): DELETE FROM t WHERE a = 0
            conn->name (0xacf68e8): con1
            conn->cur_query (0xacf9f48): DELETE FROM t WHERE a = 0
            Attempting backtrace...
            stack_bottom = 0x0 thread_stack 0x49000
            /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded(my_print_stacktrace+0x3b)[0x829e8db]
            /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded[0x8280b77]
            /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded[0x8280bb5]
            [0xb76fb400]
            [0xb76fb424]
            /lib/i386-linux-gnu/libc.so.6(gsignal+0x4f)[0xb71d71ef]
            /lib/i386-linux-gnu/libc.so.6(abort+0x175)[0xb71da835]
            /lib/i386-linux-gnu/libc.so.6(+0x27095)[0xb71d0095]
            mysys/stacktrace.c:269(my_print_stacktrace)[0xb71d0147]
            /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded[0x8a6a4cf]
            /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded[0x8a6a90f]
            row/row0upd.cc:2431(row_upd_sec_index_entry)[0x8a6cb34]
            row/row0upd.cc:3422(row_upd_step(que_thr_t*))[0x8a6ce81]
            /mnt/buildbot/build/mariadb-10.3.3/libmysqld/examples/mysqltest_embedded[0x8c6e428]
            row/row0mysql.cc:1951(row_update_for_mysql(row_prebuilt_t*))[0x893f8ea]
            sql/handler.cc:6110(handler::ha_delete_row(unsigned char const*))[0x8341b7c]
            sql/sql_delete.cc:637(mysql_delete(THD*, TABLE_LIST*, Item*, SQL_I_List<st_order>*, unsigned long long, unsigned long long, select_result*))[0x8484f3b]
            sql/sql_parse.cc:4883(mysql_execute_command(THD*))[0x871f5eb]
            sql/sql_parse.cc:7921(mysql_parse(THD*, char*, unsigned int, Parser_state*, bool, bool))[0x872553e]
            sql/sql_parse.cc:1821(dispatch_command(enum_server_command, THD*, char*, unsigned int, bool, bool))[0x87189cf]
            libmysqld/lib_sql.cc:168(emb_advanced_command)[0x82cf85c]
            sql-common/client.c:4062(mysql_send_query)[0x82c2253]
            client/mysqltest.cc:924(connection_thread)[0x826eb90]
            /lib/i386-linux-gnu/libpthread.so.0(+0x6d4c)[0xb76ded4c]
            /lib/i386-linux-gnu/libc.so.6(clone+0x5e)[0xb7293ace]
            Writing a core file...
            {noformat}
            http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10116/steps/test_2/logs/stdio
            http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10116/steps/test_1/logs/stdio
            {noformat}
            gcol.innodb_virtual_debug 'innodb' w2 [ fail ]
                    Test ended at 2017-10-24 22:18:29

            CURRENT_TEST: gcol.innodb_virtual_debug
            mysqltest: At line 87: query 'DELETE FROM t WHERE a = 0' failed: 2013: Lost connection to MySQL server during query

            The result from queries just before the failure was:
            < snip >
            start transaction;
            update t set b=b+1;
            rollback;
            SET DEBUG_SYNC = 'now SIGNAL go_ahead';
            connection default;
            check table t;
            Table Op Msg_type Msg_text
            test.t check status OK
            SELECT c FROM t;
            c
            NULL
            3
            19
            29
            SET DEBUG_SYNC = 'innodb_inplace_alter_table_enter SIGNAL start_create WAIT_FOR go_ahead';
            ALTER TABLE t ADD COLUMN x2 INT;
            connection con1;
            SET DEBUG_SYNC = 'now WAIT_FOR start_create';
            start transaction;
            DELETE FROM t WHERE a = 0;

            More results from queries before failure can be found in /mnt/buildbot/build/mariadb-10.3.3/mysql-test/var/2/log/innodb_virtual_debug.log


            Server [mysqld.1 - pid: 22229, winpid: 22229, exit: 256] failed during test run
            Server log from this test:
            ----------SERVER LOG START-----------
            2017-10-24 22:18:28 44 [ERROR] InnoDB: Record in index `idx` of table `test`.`t` was not found on update: TUPLE (info_bits=0, 2 fields): {[4] (0x00000000),[6] b(0x000000000802)} at: COMPACT RECORD(info_bits=0, 2 fields): {NULL,[6] e(0x000000000805)}
            2017-10-24 22:18:28 44 [Note] InnoDB: GIS MBR INFO: 6.32404e-322 and 3.23508e-316, -nan, -2.18566e-311

            mysqld: /home/buildbot/buildbot/build/mariadb-10.3.3/storage/innobase/row/row0upd.cc:2427: dberr_t row_upd_sec_index_entry(upd_node_t*, que_thr_t*): Assertion `0' failed.
            171024 22:18:28 [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.3-MariaDB-debug-log
            key_buffer_size=1048576
            read_buffer_size=131072
            max_used_connections=2
            max_threads=153
            thread_count=8
            It is possible that mysqld could use up to
            key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 61908 K bytes of memory
            Hope that's ok; if not, decrease some variables in the equation.

            Thread pointer: 0xabb08108
            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 = 0xacab82a0 thread_stack 0x49000
            /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld(my_print_stacktrace+0x3b)[0x8df3153]
            /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld(handle_fatal_signal+0x3cf)[0x863fb53]
            [0xb776b400]
            [0xb776b424]
            /lib/i386-linux-gnu/libc.so.6(gsignal+0x4f)[0xb72471ef]
            /lib/i386-linux-gnu/libc.so.6(abort+0x175)[0xb724a835]
            /lib/i386-linux-gnu/libc.so.6(+0x27095)[0xb7240095]
            /lib/i386-linux-gnu/libc.so.6(+0x27147)[0xb7240147]
            /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld[0x8b1bf85]
            /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld[0x8b1c6c1]
            /mnt/buildbot/build/mariadb-10.3.3/sql/mysqld[0x8b1edd5]
            mysys/stacktrace.c:269(my_print_stacktrace)[0x8b1f122]
            row/row0upd.cc:2431(row_upd_sec_index_entry)[0x8aba8fc]
            handler/ha_innodb.cc:9245(ha_innobase::delete_row(unsigned char const*))[0x8966932]
            sql/handler.cc:6110(handler::ha_delete_row(unsigned char const*))[0x864fea7]
            sql/sql_delete.cc:637(mysql_delete(THD*, TABLE_LIST*, Item*, SQL_I_List<st_order>*, unsigned long long, unsigned long long, select_result*))[0x87dd5c7]
            sql/sql_parse.cc:4883(mysql_execute_command(THD*))[0x838f83a]
            sql/sql_parse.cc:7921(mysql_parse(THD*, char*, unsigned int, Parser_state*, bool, bool))[0x8399aa3]
            sql/sql_parse.cc:1821(dispatch_command(enum_server_command, THD*, char*, unsigned int, bool, bool))[0x83867ed]
            sql/sql_parse.cc:1370(do_command(THD*))[0x83852a7]
            sql/sql_connect.cc:1418(do_handle_one_connection(CONNECT*))[0x84cdb17]
            sql/sql_connect.cc:1325(handle_one_connection)[0x84cd8a5]
            perfschema/pfs.cc:1864(pfs_spawn_thread)[0x8862625]
            /lib/i386-linux-gnu/libpthread.so.0(+0x6d4c)[0xb74f3d4c]
            /lib/i386-linux-gnu/libc.so.6(clone+0x5e)[0xb7303ace]

            Trying to get some variables.
            Some pointers may be invalid and cause the dump to abort.
            Query (0xabb17d38): DELETE FROM t WHERE a = 0
            Connection ID (thread ID): 44
            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_grouping_derived=on

            The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
            information that should help you find out what is causing the crash.
            Writing a core file
            ----------SERVER LOG END-------------
            {noformat}
            serg Sergei Golubchik made changes -
            Sprint 10.2.13 [ 228 ]
            elenst Elena Stepanova made changes -
            elenst Elena Stepanova made changes -
            Component/s Storage Engine - InnoDB [ 10129 ]
            Component/s Virtual Columns [ 10803 ]
            Fix Version/s 10.2 [ 14601 ]
            Affects Version/s 10.2 [ 14601 ]
            Assignee Sergei Golubchik [ serg ]
            elenst Elena Stepanova made changes -
            Attachment mdev14134.test [ 45121 ]
            elenst Elena Stepanova made changes -
            marko Marko Mäkelä made changes -
            marko Marko Mäkelä made changes -
            Fix Version/s 10.3.8 [ 23113 ]
            Fix Version/s 10.2.16 [ 23110 ]
            Fix Version/s 10.2 [ 14601 ]
            Fix Version/s 10.3 [ 22126 ]
            Assignee Sergei Golubchik [ serg ] Marko Mäkelä [ marko ]
            Resolution Duplicate [ 3 ]
            Status Open [ 1 ] Closed [ 6 ]
            elenst Elena Stepanova made changes -
            serg Sergei Golubchik made changes -
            Workflow MariaDB v3 [ 83151 ] MariaDB v4 [ 153030 ]

            People

              marko Marko Mäkelä
              alice Alice Sherepa
              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.