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

test failures: parts.partition_debug_innodb / parts.partition_debug_myisam introduced at 8399af81be0a87d1c0ea09164c05a9ac8eb715e2

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed (View Workflow)
    • Priority: Major
    • Resolution: Fixed
    • Affects Version/s: 10.5
    • Fix Version/s: 10.5.3
    • Component/s: Partitioning
    • Labels:
      None
    • Environment:
      ppc64le

      Description

      Per buildbot.askmonty.org/buildbot/builders/bld-p9-rhel7/builds/3364 and others, the debug builds.

      Entirely repeatable:

      0126169e08c (commit before failure) is successful for both tests

      https://github.com/MariaDB/server/commit/8399af81be0a87d1c0ea09164c05a9ac8eb715e2 introduces the failure.

      Reverting the commit at the current 10.5 is clean and allows these tests to pass.

      Innodb gets different table identifiers in the recovery log. Further into the test the difference in ids in tablespace vs recovery log continue to differ.

      parts.partition_debug_innodb

      parts.partition_debug_innodb 'innodb'    [ fail ]
              Test ended at 2020-05-06 19:05:18
       
      CURRENT_TEST: parts.partition_debug_innodb
      mysqltest: In included file "./suite/parts/inc/partition_fail.inc": 
      included from ./suite/parts/inc/partition_fail_add.inc at line 28:
      included from ./suite/parts/inc/partition_mgm_crash.inc at line 25:
      included from /home/ozlabs/mariadb-server-10.5/mysql-test/suite/parts/t/partition_debug_innodb.test at line 66:
      At line 17: query 'SHOW CREATE TABLE t1' failed: 2002: Can't connect to local MySQL server through socket '/home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/tmp/' (111)
       
      The result from queries just before the failure was:
      < snip >
      a	b
      1	Original from partition p0
      11	Original from partition p1
      12	Original from partition p1
      13	Original from partition p1
      14	Original from partition p1
      2	Original from partition p0
      3	Original from partition p0
      4	Original from partition p0
      ALTER TABLE t1 ADD PARTITION
      (PARTITION p20 VALUES IN (20,21,22,23,24,25,26,27,28,29));
      ERROR HY000: Lost connection to MySQL server during query
      # State after failure
      db.opt
      t1#P#p0.ibd
      t1#P#p10.ibd
      t1#P#p20.ibd
      t1.frm
      t1.par
      SHOW CREATE TABLE t1;
       
      More results from queries before failure can be found in /home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/log/partition_debug_innodb.log
       
       
      Server [mysqld.1 - pid: 21909, winpid: 21909, exit: 256] failed during test run
      Server log from this test:
      ----------SERVER LOG START-----------
      2020-05-06 19:05:06 0 [Note] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd (mysqld 10.5.3-MariaDB-debug-log) starting as process 21723 ...
      2020-05-06 19:05:06 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32194)
      2020-05-06 19:05:06 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
      2020-05-06 19:05:06 0 [Note] Plugin 'SEQUENCE' is disabled.
      2020-05-06 19:05:06 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
      2020-05-06 19:05:06 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
      2020-05-06 19:05:06 0 [Note] InnoDB: Uses event mutexes
      2020-05-06 19:05:06 0 [Note] InnoDB: Compressed tables use zlib 1.2.7
      2020-05-06 19:05:06 0 [Note] InnoDB: Number of pools: 1
      2020-05-06 19:05:06 0 [Note] InnoDB: Using POWER8 crc32 instructions
      2020-05-06 19:05:06 0 [Note] mariadbd: O_TMPFILE is not supported on /home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
      2020-05-06 19:05:06 0 [Note] InnoDB: Initializing buffer pool, total size = 33554432, chunk size = 33554432
      2020-05-06 19:05:06 0 [Note] InnoDB: Completed initialization of buffer pool
      2020-05-06 19:05:06 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
      2020-05-06 19:05:06 0 [Note] InnoDB: 128 rollback segments are active.
      2020-05-06 19:05:06 0 [Note] InnoDB: Creating shared tablespace for temporary tables
      2020-05-06 19:05:06 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2020-05-06 19:05:06 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
      2020-05-06 19:05:06 0 [Note] InnoDB: 10.5.3 started; log sequence number 47785; transaction id 28
      2020-05-06 19:05:06 0 [Note] InnoDB: Loading buffer pool(s) from /dev/shm/var_auto_V637/mysqld.1/data/ib_buffer_pool
      2020-05-06 19:05:06 0 [Warning] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd: unknown option '--loose-pam-debug'
      2020-05-06 19:05:06 0 [Warning] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd: unknown option '--loose-aria'
      2020-05-06 19:05:06 0 [Note] InnoDB: Buffer pool(s) load completed at 200506 19:05:06
      2020-05-06 19:05:06 0 [Note] Server socket created on IP: '127.0.0.1'.
      2020-05-06 19:05:06 0 [Note] Reading of all Master_info entries succeeded
      2020-05-06 19:05:06 0 [Note] Added new Master_info '' to hash table
      2020-05-06 19:05:06 0 [Note] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd: ready for connections.
      Version: '10.5.3-MariaDB-debug-log'  socket: '/home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
      SIGKILL myself
      2020-05-06 19:05:06 0 [Note] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd (mysqld 10.5.3-MariaDB-debug-log) starting as process 21744 ...
      2020-05-06 19:05:06 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32194)
      2020-05-06 19:05:06 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
      2020-05-06 19:05:06 0 [Note] Plugin 'SEQUENCE' is disabled.
      2020-05-06 19:05:06 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
      2020-05-06 19:05:06 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
      2020-05-06 19:05:06 0 [Note] InnoDB: Uses event mutexes
      2020-05-06 19:05:06 0 [Note] InnoDB: Compressed tables use zlib 1.2.7
      2020-05-06 19:05:06 0 [Note] InnoDB: Number of pools: 1
      2020-05-06 19:05:06 0 [Note] InnoDB: Using POWER8 crc32 instructions
      2020-05-06 19:05:06 0 [Note] mariadbd: O_TMPFILE is not supported on /home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
      2020-05-06 19:05:06 0 [Note] InnoDB: Initializing buffer pool, total size = 33554432, chunk size = 33554432
      2020-05-06 19:05:06 0 [Note] InnoDB: Completed initialization of buffer pool
      2020-05-06 19:05:06 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
      2020-05-06 19:05:06 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=47785
      2020-05-06 19:05:06 0 [Note] InnoDB: Starting final batch to recover 13 pages from redo log.
      2020-05-06 19:05:06 0 [Note] InnoDB: 128 rollback segments are active.
      2020-05-06 19:05:06 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2020-05-06 19:05:06 0 [Note] InnoDB: Creating shared tablespace for temporary tables
      2020-05-06 19:05:06 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2020-05-06 19:05:06 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
      2020-05-06 19:05:06 0 [Note] InnoDB: 10.5.3 started; log sequence number 50718; transaction id 36
      2020-05-06 19:05:06 0 [Note] InnoDB: Loading buffer pool(s) from /dev/shm/var_auto_V637/mysqld.1/data/ib_buffer_pool
      2020-05-06 19:05:06 0 [Note] Plugin 'unix_socket' is disabled.
      2020-05-06 19:05:06 0 [Warning] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd: unknown option '--loose-pam-debug'
      2020-05-06 19:05:06 0 [Warning] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd: unknown option '--loose-aria'
      2020-05-06 19:05:06 0 [Note] InnoDB: Buffer pool(s) load completed at 200506 19:05:06
      2020-05-06 19:05:06 0 [Note] Server socket created on IP: '127.0.0.1'.
      2020-05-06 19:05:06 0 [Note] Reading of all Master_info entries succeeded
      2020-05-06 19:05:06 0 [Note] Added new Master_info '' to hash table
      2020-05-06 19:05:06 0 [Note] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd: ready for connections.
      Version: '10.5.3-MariaDB-debug-log'  socket: '/home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
       
      SIGKILL myself
      2020-05-06 19:05:07 0 [Note] /home/ozlabs/build-mariadb-server-10.5-debug/sql/mariadbd (mysqld 10.5.3-MariaDB-debug-log) starting as process 21762 
      .....
       
      2020-05-06 19:05:07 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=47785
      2020-05-06 19:05:07 0 [Note] InnoDB: Ignoring data file './test/t1#P#p0.ibd' with space ID 7, since the redo log references ./test/t1#P#p0.ibd with space ID 5.
      2020-05-06 19:05:07 0 [Note] InnoDB: Ignoring data file './test/t1#P#p10.ibd' with space ID 8, since the redo log references ./test/t1#P#p10.ibd with space ID 6.
      2020-05-06 19:05:07 0 [Note] InnoDB: Starting final batch to recover 15 pages from redo log.
      

      parts.partition_debug_myisam

      parts.partition_debug_myisam             [ fail ]
              Test ended at 2020-05-06 19:12:40
       
      CURRENT_TEST: parts.partition_debug_myisam
      mysqltest: In included file "./suite/parts/inc/partition_fail.inc": 
      included from ./suite/parts/inc/partition_fail_add.inc at line 28:
      included from ./suite/parts/inc/partition_mgm_crash.inc at line 25:
      included from /home/ozlabs/mariadb-server-10.5/mysql-test/suite/parts/t/partition_debug_myisam.test at line 15:
      At line 17: query 'SHOW CREATE TABLE t1' failed: 2002: Can't connect to local MySQL server through socket '/home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/tmp/' (111)
       
      The result from queries just before the failure was:
      < snip >
      12	Original from partition p1
      13	Original from partition p1
      14	Original from partition p1
      2	Original from partition p0
      3	Original from partition p0
      4	Original from partition p0
      ALTER TABLE t1 ADD PARTITION
      (PARTITION p20 VALUES IN (20,21,22,23,24,25,26,27,28,29));
      ERROR HY000: Lost connection to MySQL server during query
      # State after failure
      db.opt
      t1#P#p0.MYD
      t1#P#p0.MYI
      t1#P#p10.MYD
      t1#P#p10.MYI
      t1#P#p20.MYD
      t1#P#p20.MYI
      t1.frm
      t1.par
      SHOW CREATE TABLE t1;
       
      More results from queries before failure can be found in /home/ozlabs/build-mariadb-server-10.5-debug/mysql-test/var/log/partition_debug_myisam.log
      

      Nothing Aria specific was in the server logs.

        Attachments

          Activity

            People

            Assignee:
            monty Michael Widenius
            Reporter:
            danblack Daniel Black
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved: