[MDEV-12746] rpl.rpl_parallel_optimistic_nobinlog fails committing out of order at retry - Jira

XML

Word

Printable

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Critical
Resolution: Fixed
Affects Version/s: 10.2(EOL)
Fix Version/s: 10.2.14
Component/s: Replication
Labels:
- optimistic
- parallelslave

Sprint:
10.2.14

Description

http://buildbot.askmonty.org/buildbot/builders/kvm-deb-trusty-amd64/builds/5752

rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w3 [ fail ]

        Test ended at 2017-05-05 18:59:40

CURRENT_TEST: rpl.rpl_parallel_optimistic_nobinlog

mysqltest: In included file "./include/wait_for_slave_param.inc":

included from ./include/wait_for_slave_sql_to_start.inc at line 32:

included from ./include/wait_for_slave_to_start.inc at line 27:

included from ./include/start_slave.inc at line 35:

included from /usr/share/mysql/mysql-test/suite/rpl/t/rpl_parallel_optimistic_nobinlog.test at line 58:

At line 119: Timeout in include/wait_for_slave_param.inc

The result from queries just before the failure was:

< snip >

master-bin.000001	2840	Query	1	2948	use `test`; INSERT INTO t1 SELECT 8, COUNT(*) FROM t2

master-bin.000001	2948	Xid	1	2979	COMMIT /* xid=117 */

master-bin.000001	2979	Gtid	1	3021	BEGIN GTID 0-1-16

master-bin.000001	3021	Query	1	3129	use `test`; INSERT INTO t2 SELECT 9, COUNT(*) FROM t1

master-bin.000001	3129	Xid	1	3160	COMMIT /* xid=118 */

master-bin.000001	3160	Gtid	1	3202	BEGIN GTID 0-1-17

master-bin.000001	3202	Query	1	3310	use `test`; INSERT INTO t1 SELECT 9, COUNT(*) FROM t2

master-bin.000001	3310	Xid	1	3341	COMMIT /* xid=119 */

master-bin.000001	3341	Gtid	1	3383	BEGIN GTID 0-1-18

master-bin.000001	3383	Query	1	3492	use `test`; INSERT INTO t1 SELECT 10, COUNT(*) FROM t2

master-bin.000001	3492	Xid	1	3523	COMMIT /* xid=120 */

master-bin.000001	3523	Gtid	1	3565	BEGIN GTID 0-1-19

master-bin.000001	3565	Query	1	3674	use `test`; INSERT INTO t2 SELECT 10, COUNT(*) FROM t1

master-bin.000001	3674	Xid	1	3705	COMMIT /* xid=121 */

**** SHOW RELAYLOG EVENTS on server_1 ****

relaylog_name = 'No such row'

SHOW RELAYLOG EVENTS IN 'No such row';

Log_name	Pos	Event_type	Server_id	End_log_pos	Info

connection server_2;

More results from queries before failure can be found in /run/shm/var/3/log/rpl_parallel_optimistic_nobinlog.log

 - saving '/run/shm/var/3/log/rpl.rpl_parallel_optimistic_nobinlog-innodb,stmt/' to '/run/shm/var/log/rpl.rpl_parallel_optimistic_nobinlog-innodb,stmt/'

Retrying test rpl.rpl_parallel_optimistic_nobinlog, attempt(2/3)...

worker[3] > Restart  - not started

worker[3] > Restart  - not started

***Warnings generated in error logs during shutdown after running tests: rpl.rpl_parallel_optimistic_nobinlog

2017-05-05 18:54:33 140175725987584 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-05 18:54:33 140175726896896 [ERROR] Slave worker thread retried transaction 10 time(s) in vain, giving up. Consider raising the value of the slave_transaction_retries variable.

2017-05-05 18:54:33 140175726896896 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175727200000 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175727200000 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175727200000 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175727503104 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175727503104 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175727503104 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175727806208 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175727806208 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175727806208 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175728109312 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175728109312 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175728109312 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175728412416 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175728412416 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175728412416 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175854655232 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175854655232 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175854655232 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175725987584 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175725987584 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175725987584 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175726290688 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175726290688 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175726290688 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175726593792 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-05 18:54:33 140175726593792 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175726593792 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-05 18:54:33 140175726896896 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-05 18:54:33 140175726896896 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

http://buildbot.askmonty.org/buildbot/builders/kvm-bintar-quantal-x86/builds/6954/steps/test/logs/stdio

rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w2 [ fail ]

        Test ended at 2017-05-04 02:12:40

CURRENT_TEST: rpl.rpl_parallel_optimistic_nobinlog

mysqltest: In included file "./include/sync_with_master_gtid.inc":

included from /usr/local/mariadb-10.2.6-linux-i686/mysql-test/suite/rpl/t/rpl_parallel_optimistic_nobinlog.test at line 59:

At line 48: Failed to sync with master

The result from queries just before the failure was:

< snip >

7	5

8	7

9	8

10	8

SELECT * FROM t2 ORDER BY a;

a	b

1	0

2	0

4	4

5	5

6	5

7	7

8	7

9	8

10	10

include/save_master_gtid.inc

connection server_2;

include/start_slave.inc

include/sync_with_master_gtid.inc

Timeout in master_gtid_wait('0-1-19', 120), current slave GTID position is: 0-1-7.

More results from queries before failure can be found in /usr/local/mariadb-10.2.6-linux-i686/mysql-test/var/2/log/rpl_parallel_optimistic_nobinlog.log

 - saving '/usr/local/mariadb-10.2.6-linux-i686/mysql-test/var/2/log/rpl.rpl_parallel_optimistic_nobinlog-innodb,stmt/' to '/usr/local/mariadb-10.2.6-linux-i686/mysql-test/var/log/rpl.rpl_parallel_optimistic_nobinlog-innodb,stmt/'

Retrying test rpl.rpl_parallel_optimistic_nobinlog, attempt(2/3)...

worker[2] > Restart  - not started

worker[2] > Restart  - not started

***Warnings generated in error logs during shutdown after running tests: rpl.rpl_parallel_optimistic_nobinlog

2017-05-04  2:10:40 2806377280 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction

2017-05-04  2:10:40 2806680384 [ERROR] Slave worker thread retried transaction 10 time(s) in vain, giving up. Consider raising the value of the slave_transaction_retries variable.

2017-05-04  2:10:40 2806680384 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2806983488 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2806983488 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2806983488 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2816867136 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2816867136 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2816867136 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2817170240 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2817170240 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2817170240 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2817473344 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2817473344 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2817473344 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2827356992 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2827356992 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2827356992 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2827660096 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2827660096 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2827660096 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2827963200 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2827963200 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2827963200 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2754583360 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2754583360 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2754583360 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2806377280 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2806377280 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2806377280 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2806680384 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2806680384 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2806983488 [Warning] Slave: Connection was killed Error_code: 1927

2017-05-04  2:10:40 2806983488 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2017-05-04  2:10:40 2806983488 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2806983488 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2017-05-04  2:10:40 2806983488 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

http://buildbot.askmonty.org/buildbot/builders/kvm-deb-yakkety-x86/builds/849/steps/mtr/logs/stdio

rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w2 [ fail ]
Test ended at 2017-04-23 04:22:55

CURRENT_TEST: rpl.rpl_parallel_optimistic_nobinlog
--- /usr/share/mysql/mysql-test/suite/rpl/r/rpl_parallel_optimistic_nobinlog.result 2017-04-23 02:50:20.000000000 -0400
+++ /dev/shm/var/2/log/rpl_parallel_optimistic_nobinlog.reject 2017-04-23 04:22:54.987802808 -0400
@@ -64,11 +64,8 @@
3 0
4 2
5 3
-6 5
-7 5
-8 7
-9 8
-10 8
+9 6
+10 6
SELECT * FROM t2 ORDER BY a;
a b
1 0
@@ -76,10 +73,8 @@
4 4
5 5
6 5
-7 7
-8 7
-9 8
-10 10
+9 5
+10 7
connection server_2;
include/stop_slave.inc
SET GLOBAL tx_isolation= @old_isolation;

mysqltest: Result length mismatch

Attachments

Activity

People

Assignee:: Andrei Elkin

Reporter:: Elena Stepanova

Votes:: 0 Vote for this issue

Watchers:: 4 Start watching this issue

Dates

Created:: 2017-05-09 10:52

Updated:: 2019-10-25 15:15

Resolved:: 2018-03-14 07:10

Git Integration

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