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

Main.mysql test crashes due to packets out of order in debug build

    XMLWordPrintable

Details

    • Bug
    • Status: Open (View Workflow)
    • Major
    • Resolution: Unresolved
    • 10.1.47, 10.1.48
    • 10.4, 10.5
    • Server
    • None

    Description

      When running the mtr tests for the debug build of 10.1.48 I found that the test main.mysql fails due to a failed assertion.

      Built the server using the following script: ./BUILD/compile-pentium64-debug

      Failed assertion: mariadb/sql/net_serv.cc:1078

        packets_out_of_order:
          {
            DBUG_PRINT("error",
                       ("Packets out of order (Found: %d, expected %u)",
                        (int) net->buff[net->where_b + 3],
                        net->pkt_nr));
            EXTRA_DEBUG_ASSERT(0);
      

      Also note in the error below: ERROR 2027 (HY000) at line 1: Malformed packet

      Test log including stack trace:
       
      main.mysql                               w19 [ fail ]
              Test ended at 2020-12-17 17:55:02
       
      CURRENT_TEST: main.mysql
      ERROR 1050 (42S01) at line 1: Table 't2' already exists
      ERROR: Can't initialize batch_readline - may be the input source is a directory or a block device.
      ERROR 1148 (42000) at line 1: The used command is not allowed with this MariaDB version
      ERROR 2027 (HY000) at line 1: Malformed packet
      mysqltest: At line 698: query 'select count(*) from t1' failed: 2013: Lost connection to MySQL server during query
       
      The result from queries just before the failure was:
      < snip >
      select * from "a1\""b1";
      a
      1
      2
      drop table "a1\""b1";
      set sql_mode=default;
      create table t1 (a text);
      select count(*) from t1;
      count(*)
      41
      truncate table t1;
      select count(*) from t1;
      count(*)
      41
      truncate table t1;
      select count(*) from t1;
      count(*)
      0
      truncate table t1;
      select count(*) from t1;
       
      More results from queries before failure can be found in /local/mariadb_10_1/mariadb/mysql-test/var/19/log/mysql.log
       
       - found 'core.48297' (0/5)
       
      Trying 'dbx' to get a backtrace
       
      Trying 'gdb' to get a backtrace from coredump /local/mariadb_10_1/mariadb/mysql-test/var/19/log/main.mysql/mysqld.1/data/core.48297
      Core generated by '/local/mariadb_10_1/mariadb/sql/mysqld'
      Output from gdb follows. The first stack trace is from the failing thread.
      The following stack traces are from all threads (so the failing one is
      duplicated).
      --------------------------
      [New LWP 48601]
      [New LWP 48300]
      [New LWP 48302]
      [New LWP 48307]
      [New LWP 48297]
      [New LWP 48298]
      [New LWP 48299]
      [Thread debugging using libthread_db enabled]
      Using host libthread_db library "/lib64/libthread_db.so.1".
      Core was generated by `/local/mariadb_10_1/mariadb/sql/mysqld --defaults-group-suffix=.1'.
      Program terminated with signal SIGABRT, Aborted.
      #0  0x00007f723b551754 in pthread_kill () from /lib64/libpthread.so.0
      [Current thread is 1 (Thread 0x7f7232587700 (LWP 48601))]
      #0  0x00007f723b551754 in pthread_kill () from /lib64/libpthread.so.0
      #1  0x0000000000ee2433 in my_write_core (sig=6) at /mariadb_10_1/mariadb/mysys/stacktrace.c:387
      #2  0x00000000008b8f3f in handle_fatal_signal (sig=6) at /mariadb_10_1/mariadb/sql/signal_handler.cc:296
      #3  <signal handler called>
      #4  0x00007f72399ddae0 in raise () from /lib64/libc.so.6
      #5  0x00007f72399def88 in abort () from /lib64/libc.so.6
      #6  0x00007f72399d68ea in __assert_fail_base () from /lib64/libc.so.6
      #7  0x00007f72399d6962 in __assert_fail () from /lib64/libc.so.6
      #8  0x00000000005c1d51 in my_real_read (net=0x2b61860, complen=0x7f72325851b8, header=0 '\000') at /mariadb_10_1/mariadb/sql/net_serv.cc:1078
      #9  0x00000000005c1db5 in my_net_read_packet (net=0x2b61860, read_from_server=0 '\000') at /mariadb_10_1/mariadb/sql/net_serv.cc:1141
      #10 0x0000000000a4a33e in _my_b_net_read (info=0x7f72325855f8, Buffer=0x7f723258529f "", Count=1) at /mariadb_10_1/mariadb/sql/mf_iocache.cc:61
      #11 0x0000000000ec00b1 in _my_b_get (info=0x7f72325855f8) at /mariadb_10_1/mariadb/mysys/mf_iocache.c:1612
      #12 0x0000000000a13e02 in my_b_get (info=0x7f72325855f8) at /mariadb_10_1/mariadb/include/my_sys.h:548
      #13 0x0000000000a17f5e in READ_INFO::read_field (this=0x7f7232585560) at /mariadb_10_1/mariadb/sql/sql_load.cc:1614
      #14 0x0000000000a1688e in read_sep_field (thd=0x2b615a0, info=..., table_list=0x7f72140105a8, fields_vars=..., set_fields=..., set_values=..., read_info=..., enclosed=..., skip_lines=0, ignore_check_option_errors=true) at /mariadb_10_1/mariadb/sql/sql_load.cc:1050
      #15 0x0000000000a1537f in mysql_load (thd=0x2b615a0, ex=0x7f7214010518, table_list=0x7f72140105a8, fields_vars=..., set_fields=..., set_values=..., handle_duplicates=DUP_ERROR, ignore=true, read_file_from_client=true) at /mariadb_10_1/mariadb/sql/sql_load.cc:591
      #16 0x000000000068fb93 in mysql_execute_command (thd=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_parse.cc:4086
      #17 0x0000000000699168 in mysql_parse (thd=0x2b615a0, rawbuf=0x7f7214010378 "load data local infile '/local/mariadb_10_1/mariadb/mysql-test/var/19/tmp/bug.sql' into table test.t1", length=114, parser_state=0x7f7232586530) at /mariadb_10_1/mariadb/sql/sql_parse.cc:7228
      #18 0x0000000000688857 in dispatch_command (command=COM_QUERY, thd=0x2b615a0, packet=0x2b68801 "", packet_length=115) at /mariadb_10_1/mariadb/sql/sql_parse.cc:1492
      #19 0x0000000000687766 in do_command (thd=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_parse.cc:1124
      #20 0x00000000007ba3c0 in do_handle_one_connection (thd_arg=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_connect.cc:1331
      #21 0x00000000007ba0fb in handle_one_connection (arg=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_connect.cc:1242
      #22 0x0000000000a7bf2a in pfs_spawn_thread (arg=0x2b6c890) at /mariadb_10_1/mariadb/storage/perfschema/pfs.cc:1868
      #23 0x00007f723b54a40b in start_thread () from /lib64/libpthread.so.0
      #24 0x00007f7239a96e7f in clone () from /lib64/libc.so.6
       
      Thread 7 (Thread 0x7f7233069700 (LWP 48299)):
      #0  0x00007f723b55061e in pthread_cond_timedwait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
      #1  0x0000000000ee93d4 in safe_cond_timedwait (cond=0x213b540 <COND_checkpoint>, mp=0x213b480 <LOCK_checkpoint>, abstime=0x7f7233068cf0, file=0x10b9550 "/mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h", line=1198) at /mariadb_10_1/mariadb/mysys/thr_mutex.c:547
      #2  0x0000000000ad4655 in inline_mysql_cond_timedwait (that=0x213b540 <COND_checkpoint>, mutex=0x213b480 <LOCK_checkpoint>, abstime=0x7f7233068cf0, src_file=0x10b9598 "/mariadb_10_1/mariadb/storage/maria/ma_servicethread.c", src_line=116) at /mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h:1198
      #3  0x0000000000ad4b3b in my_service_thread_sleep (control=0x1740c20 <checkpoint_control>, sleep_time=29000000000) at /mariadb_10_1/mariadb/storage/maria/ma_servicethread.c:115
      #4  0x0000000000ac9ccd in ma_checkpoint_background (arg=0x1e) at /mariadb_10_1/mariadb/storage/maria/ma_checkpoint.c:708
      #5  0x0000000000a7bf2a in pfs_spawn_thread (arg=0x29c6dc0) at /mariadb_10_1/mariadb/storage/perfschema/pfs.cc:1868
      #6  0x00007f723b54a40b in start_thread () from /lib64/libpthread.so.0
      #7  0x00007f7239a96e7f in clone () from /lib64/libc.so.6
       
      Thread 6 (Thread 0x7f72345d3700 (LWP 48298)):
      #0  0x00007f723b55061e in pthread_cond_timedwait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
      #1  0x0000000000ee93d4 in safe_cond_timedwait (cond=0x2187980 <COND_timer>, mp=0x21878c0 <LOCK_timer>, abstime=0x7f72345d2d60, file=0x1187270 "/mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h", line=1198) at /mariadb_10_1/mariadb/mysys/thr_mutex.c:547
      #2  0x0000000000eea368 in inline_mysql_cond_timedwait (that=0x2187980 <COND_timer>, mutex=0x21878c0 <LOCK_timer>, abstime=0x7f72345d2d60, src_file=0x11872b8 "/mariadb_10_1/mariadb/mysys/thr_timer.c", src_line=292) at /mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h:1198
      #3  0x0000000000eeae39 in timer_handler (arg=0x0) at /mariadb_10_1/mariadb/mysys/thr_timer.c:292
      #4  0x0000000000a7bf2a in pfs_spawn_thread (arg=0x29758c0) at /mariadb_10_1/mariadb/storage/perfschema/pfs.cc:1868
      #5  0x00007f723b54a40b in start_thread () from /lib64/libpthread.so.0
      #6  0x00007f7239a96e7f in clone () from /lib64/libc.so.6
       
      Thread 5 (Thread 0x7f723b969840 (LWP 48297)):
      #0  0x00007f7239a8d029 in poll () from /lib64/libc.so.6
      #1  0x00000000005b8f1c in handle_connections_sockets () at /mariadb_10_1/mariadb/sql/mysqld.cc:6602
      #2  0x00000000005b7fad in mysqld_main (argc=130, argv=0x2845100) at /mariadb_10_1/mariadb/sql/mysqld.cc:6027
      #3  0x00000000005ad1e7 in main (argc=6, argv=0x7fffc2cca7d8) at /mariadb_10_1/mariadb/sql/main.cc:25
       
      Thread 4 (Thread 0x7f72325d2700 (LWP 48307)):
      #0  0x00007f723b55061e in pthread_cond_timedwait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
      #1  0x0000000000ee93d4 in safe_cond_timedwait (cond=0x7f7220173178, mp=0x7f721406d0f8, abstime=0x7f72325d0290, file=0x1185a40 "/mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h", line=1198) at /mariadb_10_1/mariadb/mysys/thr_mutex.c:547
      #2  0x0000000000ee42a5 in inline_mysql_cond_timedwait (that=0x7f7220173178, mutex=0x7f721406d0f8, abstime=0x7f72325d0290, src_file=0x1185c58 "/mariadb_10_1/mariadb/mysys/thr_lock.c", src_line=575) at /mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h:1198
      #3  0x0000000000ee54d7 in wait_for_lock (wait=0x7f721406d1a8, data=0x7f722007c3f8, in_wait_list=0 '\000', lock_wait_timeout=31536000) at /mariadb_10_1/mariadb/mysys/thr_lock.c:575
      #4  0x0000000000ee64ea in thr_lock (data=0x7f722007c3f8, owner=0x2b56ec0, lock_wait_timeout=31536000) at /mariadb_10_1/mariadb/mysys/thr_lock.c:1012
      #5  0x0000000000ee700a in thr_multi_lock (data=0x7f7220072438, count=1, owner=0x2b56ec0, lock_wait_timeout=31536000) at /mariadb_10_1/mariadb/mysys/thr_lock.c:1291
      #6  0x00000000009a2211 in mysql_lock_tables (thd=0x2b55640, sql_lock=0x7f7220072418, flags=0) at /mariadb_10_1/mariadb/sql/lock.cc:320
      #7  0x00000000009a2045 in mysql_lock_tables (thd=0x2b55640, tables=0x7f7220072410, count=1, flags=0) at /mariadb_10_1/mariadb/sql/lock.cc:275
      #8  0x000000000062ba61 in lock_tables (thd=0x2b55640, tables=0x7f7220071d70, count=1, flags=0) at /mariadb_10_1/mariadb/sql/sql_base.cc:5566
      #9  0x000000000062b541 in open_and_lock_tables (thd=0x2b55640, options=..., tables=0x7f7220071d70, derived=true, flags=0, prelocking_strategy=0x7f72325d0688) at /mariadb_10_1/mariadb/sql/sql_base.cc:5395
      #10 0x00000000005f61bd in open_and_lock_tables (thd=0x2b55640, tables=0x7f7220071d70, derived=true, flags=0) at /mariadb_10_1/mariadb/sql/sql_base.h:547
      #11 0x0000000000695415 in execute_sqlcom_select (thd=0x2b55640, all_tables=0x7f7220071d70) at /mariadb_10_1/mariadb/sql/sql_parse.cc:5634
      #12 0x000000000068c832 in mysql_execute_command (thd=0x2b55640) at /mariadb_10_1/mariadb/sql/sql_parse.cc:3060
      #13 0x0000000000699168 in mysql_parse (thd=0x2b55640, rawbuf=0x7f7220071aa8 "select count(*) from t1", length=23, parser_state=0x7f72325d1530) at /mariadb_10_1/mariadb/sql/sql_parse.cc:7228
      #14 0x0000000000688857 in dispatch_command (command=COM_QUERY, thd=0x2b55640, packet=0x2b5bb81 "select count(*) from t1", packet_length=23) at /mariadb_10_1/mariadb/sql/sql_parse.cc:1492
      #15 0x0000000000687766 in do_command (thd=0x2b55640) at /mariadb_10_1/mariadb/sql/sql_parse.cc:1124
      #16 0x00000000007ba3c0 in do_handle_one_connection (thd_arg=0x2b55640) at /mariadb_10_1/mariadb/sql/sql_connect.cc:1331
      #17 0x00000000007ba0fb in handle_one_connection (arg=0x2b55640) at /mariadb_10_1/mariadb/sql/sql_connect.cc:1242
      #18 0x0000000000a7bf2a in pfs_spawn_thread (arg=0x2b61330) at /mariadb_10_1/mariadb/storage/perfschema/pfs.cc:1868
      #19 0x00007f723b54a40b in start_thread () from /lib64/libpthread.so.0
      #20 0x00007f7239a96e7f in clone () from /lib64/libc.so.6
       
      Thread 3 (Thread 0x7f723261d700 (LWP 48302)):
      #0  0x00007f723b550277 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
      #1  0x0000000000ee9043 in safe_cond_wait (cond=0x19046c0 <COND_slave_background>, mp=0x1907780 <LOCK_slave_background>, file=0xf78580 "/mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h", line=1161) at /mariadb_10_1/mariadb/mysys/thr_mutex.c:493
      #2  0x00000000005d500e in inline_mysql_cond_wait (that=0x19046c0 <COND_slave_background>, mutex=0x1907780 <LOCK_slave_background>, src_file=0xf78e78 "/mariadb_10_1/mariadb/sql/slave.cc", src_line=339) at /mariadb_10_1/mariadb/include/mysql/psi/mysql_thread.h:1161
      #3  0x00000000005d5d22 in handle_slave_background (arg=0x0) at /mariadb_10_1/mariadb/sql/slave.cc:339
      #4  0x0000000000a7bf2a in pfs_spawn_thread (arg=0x2a9c6c0) at /mariadb_10_1/mariadb/storage/perfschema/pfs.cc:1868
      #5  0x00007f723b54a40b in start_thread () from /lib64/libpthread.so.0
      #6  0x00007f7239a96e7f in clone () from /lib64/libc.so.6
       
      Thread 2 (Thread 0x7f7232668700 (LWP 48300)):
      #0  0x00007f723b55432a in do_sigwait () from /lib64/libpthread.so.0
      #1  0x00007f723b5543ac in sigwait () from /lib64/libpthread.so.0
      #2  0x00000000005b32c3 in signal_hand (arg=0x0) at /mariadb_10_1/mariadb/sql/mysqld.cc:3457
      #3  0x0000000000a7bf2a in pfs_spawn_thread (arg=0x2a90ea0) at /mariadb_10_1/mariadb/storage/perfschema/pfs.cc:1868
      #4  0x00007f723b54a40b in start_thread () from /lib64/libpthread.so.0
      #5  0x00007f7239a96e7f in clone () from /lib64/libc.so.6
       
      Thread 1 (Thread 0x7f7232587700 (LWP 48601)):
      #0  0x00007f723b551754 in pthread_kill () from /lib64/libpthread.so.0
      #1  0x0000000000ee2433 in my_write_core (sig=6) at /mariadb_10_1/mariadb/mysys/stacktrace.c:387
      #2  0x00000000008b8f3f in handle_fatal_signal (sig=6) at /mariadb_10_1/mariadb/sql/signal_handler.cc:296
      #3  <signal handler called>
      #4  0x00007f72399ddae0 in raise () from /lib64/libc.so.6
      #5  0x00007f72399def88 in abort () from /lib64/libc.so.6
      #6  0x00007f72399d68ea in __assert_fail_base () from /lib64/libc.so.6
      #7  0x00007f72399d6962 in __assert_fail () from /lib64/libc.so.6
      #8  0x00000000005c1d51 in my_real_read (net=0x2b61860, complen=0x7f72325851b8, header=0 '\000') at /mariadb_10_1/mariadb/sql/net_serv.cc:1078
      #9  0x00000000005c1db5 in my_net_read_packet (net=0x2b61860, read_from_server=0 '\000') at /mariadb_10_1/mariadb/sql/net_serv.cc:1141
      #10 0x0000000000a4a33e in _my_b_net_read (info=0x7f72325855f8, Buffer=0x7f723258529f "", Count=1) at /mariadb_10_1/mariadb/sql/mf_iocache.cc:61
      #11 0x0000000000ec00b1 in _my_b_get (info=0x7f72325855f8) at /mariadb_10_1/mariadb/mysys/mf_iocache.c:1612
      #12 0x0000000000a13e02 in my_b_get (info=0x7f72325855f8) at /mariadb_10_1/mariadb/include/my_sys.h:548
      #13 0x0000000000a17f5e in READ_INFO::read_field (this=0x7f7232585560) at /mariadb_10_1/mariadb/sql/sql_load.cc:1614
      #14 0x0000000000a1688e in read_sep_field (thd=0x2b615a0, info=..., table_list=0x7f72140105a8, fields_vars=..., set_fields=..., set_values=..., read_info=..., enclosed=..., skip_lines=0, ignore_check_option_errors=true) at /mariadb_10_1/mariadb/sql/sql_load.cc:1050
      #15 0x0000000000a1537f in mysql_load (thd=0x2b615a0, ex=0x7f7214010518, table_list=0x7f72140105a8, fields_vars=..., set_fields=..., set_values=..., handle_duplicates=DUP_ERROR, ignore=true, read_file_from_client=true) at /mariadb_10_1/mariadb/sql/sql_load.cc:591
      #16 0x000000000068fb93 in mysql_execute_command (thd=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_parse.cc:4086
      #17 0x0000000000699168 in mysql_parse (thd=0x2b615a0, rawbuf=0x7f7214010378 "load data local infile '/local/mariadb_10_1/mariadb/mysql-test/var/19/tmp/bug.sql' into table test.t1", length=114, parser_state=0x7f7232586530) at /mariadb_10_1/mariadb/sql/sql_parse.cc:7228
      #18 0x0000000000688857 in dispatch_command (command=COM_QUERY, thd=0x2b615a0, packet=0x2b68801 "", packet_length=115) at /mariadb_10_1/mariadb/sql/sql_parse.cc:1492
      #19 0x0000000000687766 in do_command (thd=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_parse.cc:1124
      #20 0x00000000007ba3c0 in do_handle_one_connection (thd_arg=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_connect.cc:1331
      #21 0x00000000007ba0fb in handle_one_connection (arg=0x2b615a0) at /mariadb_10_1/mariadb/sql/sql_connect.cc:1242
      #22 0x0000000000a7bf2a in pfs_spawn_thread (arg=0x2b6c890) at /mariadb_10_1/mariadb/storage/perfschema/pfs.cc:1868
      #23 0x00007f723b54a40b in start_thread () from /lib64/libpthread.so.0
      #24 0x00007f7239a96e7f in clone () from /lib64/libc.so.6
       
       
      
      

      Attachments

        Activity

          People

            sanja Oleksandr Byelkin
            AliMoursy Ali Moursy
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

            Dates

              Created:
              Updated:

              Git Integration

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