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

rpl.rpl_parallel_optimistic_nobinlog fails committing out of order at retry

Details

    • 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

          alice Alice Sherepa added a comment -

          recent fail http://buildbot.askmonty.org/buildbot/builders/kvm-deb-xenial-x86/builds/3142/steps/mtr/logs/stdio

          rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w1 [ fail ]
                  Test ended at 2017-08-16 16:05:40
           
          CURRENT_TEST: rpl.rpl_parallel_optimistic_nobinlog
          mysqltest: In included file "./include/sync_with_master_gtid.inc": 
          included from /usr/share/mysql/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-11.
          

          alice Alice Sherepa added a comment - recent fail http://buildbot.askmonty.org/buildbot/builders/kvm-deb-xenial-x86/builds/3142/steps/mtr/logs/stdio rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w1 [ fail ] Test ended at 2017-08-16 16:05:40   CURRENT_TEST: rpl.rpl_parallel_optimistic_nobinlog mysqltest: In included file "./include/sync_with_master_gtid.inc": included from /usr/share/mysql/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-11.
          alice Alice Sherepa added a comment -

          also failed on 10.3 (sporadic)
          http://buildbot.askmonty.org/buildbot/builders/kvm-deb-xenial-amd64/builds/2055/steps/mtr/logs/stdio

          rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w3 [ fail ]
                  Test ended at 2017-09-23 15:47:55
           
          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 /dev/shm/var/3/log/rpl_parallel_optimistic_nobinlog.log
           
           - saving '/dev/shm/var/3/log/rpl.rpl_parallel_optimistic_nobinlog-innodb,stmt/' to '/dev/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-09-23 15:42:50 22 [ERROR] Slave worker thread retried transaction 10 time(s) in vain, giving up. Consider raising the value of the slave_transaction_retries variable.
          2017-09-23 15:42:50 22 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 21 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 21 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 21 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 20 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 20 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 20 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 18 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 18 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 18 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 17 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 17 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 17 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 16 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 16 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 16 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 19 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 19 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 19 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 24 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 24 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 24 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 25 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 25 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 25 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 23 [Warning] Slave: Connection was killed Error_code: 1927
          2017-09-23 15:42:50 23 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 23 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
          2017-09-23 15:42:50 22 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213
          2017-09-23 15:42:50 22 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964
           
          worker[3] > Restart [mysqld.1 - pid: 8508, winpid: 8508] - using different config file
          worker[3] > Restart [mysqld.2 - pid: 8510, winpid: 8510] - using different config file
          rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w3 [ retry-pass ]   1074
          

          alice Alice Sherepa added a comment - also failed on 10.3 (sporadic) http://buildbot.askmonty.org/buildbot/builders/kvm-deb-xenial-amd64/builds/2055/steps/mtr/logs/stdio rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w3 [ fail ] Test ended at 2017-09-23 15:47:55   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 /dev/shm/var/3/log/rpl_parallel_optimistic_nobinlog.log   - saving '/dev/shm/var/3/log/rpl.rpl_parallel_optimistic_nobinlog-innodb,stmt/' to '/dev/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-09-23 15:42:50 22 [ERROR] Slave worker thread retried transaction 10 time(s) in vain, giving up. Consider raising the value of the slave_transaction_retries variable. 2017-09-23 15:42:50 22 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 21 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 21 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 21 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 20 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 20 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 20 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 18 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 18 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 18 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 17 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 17 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 17 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 16 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 16 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 16 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 19 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 19 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 19 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 24 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 24 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 24 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 25 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 25 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 25 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 23 [Warning] Slave: Connection was killed Error_code: 1927 2017-09-23 15:42:50 23 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 23 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2017-09-23 15:42:50 22 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2017-09-23 15:42:50 22 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964   worker[3] > Restart [mysqld.1 - pid: 8508, winpid: 8508] - using different config file worker[3] > Restart [mysqld.2 - pid: 8510, winpid: 8510] - using different config file rpl.rpl_parallel_optimistic_nobinlog 'innodb,stmt' w3 [ retry-pass ] 1074
          Elkin Andrei Elkin added a comment -

          Kristian, hello.

          You would be the most suitable reviewer of this issue in optimistic applier.
          I will ask a "local" one when you won't be able to.

          Thanks!
          Andrei

          Elkin Andrei Elkin added a comment - Kristian, hello. You would be the most suitable reviewer of this issue in optimistic applier. I will ask a "local" one when you won't be able to. Thanks! Andrei
          Elkin Andrei Elkin added a comment -

          The latest patch was approved by Kristian.

          Elkin Andrei Elkin added a comment - The latest patch was approved by Kristian.
          Elkin Andrei Elkin added a comment -

          Pushed to 30019a48bfc908.

          Elkin Andrei Elkin added a comment - Pushed to 30019a48bfc908.

          People

            Elkin Andrei Elkin
            elenst Elena Stepanova
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              Git Integration

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