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

rpl.rpl_parallel_optimistic_nobinlog fails committing out of order at retry

    XMLWordPrintable

    Details

    • 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:
            Elkin Andrei Elkin
            Reporter:
            elenst Elena Stepanova
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved: