main.partition_pruning unstable

[George Ormond Lorch III]

Somewhat difficult to reproduce reliably. I saw it on one loacl mtr run, then was able to get it again with this mtr incantation:

> ./mtr --big-test --repeat=3 --parallel=8 t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_prun
ing.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test
Logging: ./mtr --force --retry=0 --retry-failure=0 --max-test-fail=0 --no-warnings --testcase-timeout=600 --mysqld=--loose-tokudb-cache-size=512M --big-test --repeat=3 --parallel=8 t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test t/partition_pruning.test
MySQL Version 5.7.17
Checking supported features...
 - SSL connections supported
 - binaries are debug compiled
Collecting tests...
Checking leftover processes...
Removing old var directory...
Creating var directory '/ssd/percona/myrocks/percona-server-install-5.7/mysql-test/var'...
Installing system database...
Using parallel: 8
worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 13000..13009

==============================================================================

TEST WORKER RESULT TIME (ms) or COMMENT
--------------------------------------------------------------------------

worker[6] Using MTR_BUILD_THREAD 303, with reserved ports 13030..13039
worker[4] Using MTR_BUILD_THREAD 304, with reserved ports 13040..13049
worker[3] Using MTR_BUILD_THREAD 301, with reserved ports 13010..13019
worker[8] Using MTR_BUILD_THREAD 305, with reserved ports 13050..13059
worker[2] Using MTR_BUILD_THREAD 302, with reserved ports 13020..13029
worker[5] Using MTR_BUILD_THREAD 306, with reserved ports 13060..13069
worker[7] Using MTR_BUILD_THREAD 307, with reserved ports 13070..13079
main.partition_pruning w3 [ pass ] 19415
main.partition_pruning w7 [ pass ] 19345
main.partition_pruning w1 [ pass ] 19655
main.partition_pruning w5 [ pass ] 19690
main.partition_pruning w2 [ pass ] 19612
main.partition_pruning w4 [ pass ] 19497
main.partition_pruning w8 [ pass ] 19843
main.partition_pruning w6 [ pass ] 19997
main.partition_pruning w7 [ pass ] 16766
main.partition_pruning w1 [ pass ] 17858
main.partition_pruning w6 [ pass ] 17623
main.partition_pruning w3 [ pass ] 18239
main.partition_pruning w5 [ pass ] 18179
main.partition_pruning w4 [ pass ] 18528
main.partition_pruning w2 [ pass ] 18363
main.partition_pruning w8 [ pass ] 18469
main.partition_pruning w6 [ pass ] 16396
main.partition_pruning w1 [ pass ] 16728
main.partition_pruning w4 [ pass ] 16159
main.partition_pruning w7 [ pass ] 17614
main.partition_pruning w2 [ pass ] 16210
main.partition_pruning w5 [ pass ] 16654
main.partition_pruning w3 [ pass ] 17150
main.partition_pruning w8 [ pass ] 17007
main.partition_pruning w7 [ pass ] 16299
main.partition_pruning w4 [ fail ]
        Test ended at 2017-03-28 13:18:02

CURRENT_TEST: main.partition_pruning
--- /ssd/percona/myrocks/percona-server-install-5.7/mysql-test/r/partition_pruning.result 2017-03-16 20:03:21.000000000 +0300
+++ /ssd/percona/myrocks/percona-server-install-5.7/mysql-test/var/4/log/partition_pruning.reject 2017-03-28 23:18:02.148837998 +0300
@@ -4856,7 +4856,7 @@
 insert into t1 values ('aa'),('bb'),('0');
 explain partitions select * from t1 where s1 = 0 or s1 is null;
 id select_type table partitions type possible_keys key key_len ref rows filtered Extra
-1 SIMPLE t1 p0 ALL NULL NULL NULL NULL # 55.56 Using where
+1 SIMPLE t1 p0 ALL NULL NULL NULL NULL # 43.75 Using where
 Warnings:
 Warning 1681 'PARTITIONS' is deprecated and will be removed in a future release.
 Note 1003 /* select#1 */ select `test`.`t1`.`s1` AS `s1` from `test`.`t1` where ((`test`.`t1`.`s1` = 0) or isnull(`test`.`t1`.`s1`))

mysqltest: Result content mismatch

safe_process[13042]: Child process: 13043, exit: 1

Server [mysqld.1 - pid: 12605, winpid: 12605] log:
Server log from this test:
----------SERVER LOG START-----------
----------SERVER LOG END-------------

 - the logfile can be found in '/ssd/percona/myrocks/percona-server-install-5.7/mysql-test/var/log/main.partition_pruning/partition_pruning.log'
main.partition_pruning w2 [ pass ] 16726
main.partition_pruning w1 [ pass ] 17061
main.partition_pruning w3 [ pass ] 16718
main.partition_pruning w6 [ pass ] 17746
main.partition_pruning w5 [ pass ] 17083
main.partition_pruning w8 [ pass ] 17450
main.partition_pruning w7 [ pass ] 16715
main.partition_pruning w1 [ fail ]
        Test ended at 2017-03-28 13:18:20

CURRENT_TEST: main.partition_pruning
--- /ssd/percona/myrocks/percona-server-install-5.7/mysql-test/r/partition_pruning.result 2017-03-16 20:03:21.000000000 +0300
+++ /ssd/percona/myrocks/percona-server-install-5.7/mysql-test/var/1/log/partition_pruning.reject 2017-03-28 23:18:20.304838099 +0300
@@ -2702,7 +2702,7 @@
 EXPLAIN PARTITIONS SELECT * FROM t1
 WHERE b = CAST('2009-04-02 23:59:59' AS DATETIME);
 id select_type table partitions type possible_keys key key_len ref rows filtered Extra
-1 SIMPLE t1 p20090402 ALL NULL NULL NULL NULL # 33.33 Using where
+1 SIMPLE t1 p20090402 ALL NULL NULL NULL NULL # 25.00 Using where
 Warnings:
 Warning 1681 'PARTITIONS' is deprecated and will be removed in a future release.
 Note 1003 /* select#1 */ select `test`.`t1`.`a` AS `a`,`test`.`t1`.`b` AS `b` from `test`.`t1` where (`test`.`t1`.`b` = <cache>(cast('2009-04-02 23:59:59' as datetime)))
@@ -2794,7 +2794,7 @@
 Note 1003 /* select#1 */ select `test`.`t1`.`a` AS `a`,`test`.`t1`.`b` AS `b` from `test`.`t1` where (`test`.`t1`.`b` <= '2009-04-02 23:59:59')
 EXPLAIN PARTITIONS SELECT * FROM t1 WHERE b = '2009-04-02 23:59:59';
 id select_type table partitions type possible_keys key key_len ref rows filtered Extra
-1 SIMPLE t1 p20090402 ALL NULL NULL NULL NULL # 33.33 Using where
+1 SIMPLE t1 p20090402 ALL NULL NULL NULL NULL # 25.00 Using where
 Warnings:
 Warning 1681 'PARTITIONS' is deprecated and will be removed in a future release.
 Note 1003 /* select#1 */ select `test`.`t1`.`a` AS `a`,`test`.`t1`.`b` AS `b` from `test`.`t1` where (`test`.`t1`.`b` = '2009-04-02 23:59:59')
@@ -2892,7 +2892,7 @@
 EXPLAIN PARTITIONS SELECT * FROM t1
 WHERE b = CAST('2009-04-02 23:59:58' AS DATETIME);
 id select_type table partitions type possible_keys key key_len ref rows filtered Extra
-1 SIMPLE t1 p20090402 ALL NULL NULL NULL NULL # 33.33 Using where
+1 SIMPLE t1 p20090402 ALL NULL NULL NULL NULL # 25.00 Using where
 Warnings:
 Warning 1681 'PARTITIONS' is deprecated and will be removed in a future release.
 Note 1003 /* select#1 */ select `test`.`t1`.`a` AS `a`,`test`.`t1`.`b` AS `b` from `test`.`t1` where (`test`.`t1`.`b` = <cache>(cast('2009-04-02 23:59:58' as datetime)))

mysqltest: Result content mismatch

safe_process[13124]: Child process: 13125, exit: 1

Server [mysqld.1 - pid: 12604, winpid: 12604] log:
Server log from this test:
----------SERVER LOG START-----------
----------SERVER LOG END-------------

 - the logfile can be found in '/ssd/percona/myrocks/percona-server-install-5.7/mysql-test/var/log/main.partition_pruning/partition_pruning.log'
main.partition_pruning w2 [ pass ] 17378
main.partition_pruning w3 [ pass ] 17308
main.partition_pruning w6 [ pass ] 17199
main.partition_pruning w4 [ pass ] 17371
main.partition_pruning w5 [ pass ] 17213
main.partition_pruning w8 [ pass ] 16221
main.partition_pruning w7 [ pass ] 17078
main.partition_pruning w2 [ pass ] 17246
main.partition_pruning w6 [ pass ] 17331
main.partition_pruning w3 [ pass ] 17499
main.partition_pruning w5 [ pass ] 17221
main.partition_pruning w1 [ pass ] 17818
main.partition_pruning w8 [ pass ] 17518
main.partition_pruning w4 [ pass ] 18081
--------------------------------------------------------------------------
The servers were restarted 2 times
Spent 813.277 of 123 seconds executing testcases

Completed: Failed 2/48 tests, 95.83% were successful.

Failing test(s): main.partition_pruning

The log files in var/log may give you some hint of what went wrong.

If you want to report this error, please read first the documentation
at http://dev.mysql.com/doc/mysql/en/mysql-test-suite.html

mysql-test-run: *** ERROR: there were failing test cases

[Shahriyar Rzayev (rzayev-sehriyar)]

Percona now uses JIRA for bug reports so this bug report is migrated to: https://jira.percona.com/browse/PS-3670