Details
-
Bug
-
Status: Closed (View Workflow)
-
Major
-
Resolution: Fixed
-
10.5
-
None
-
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.