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

binlog.binlog_xa_recover fails sporadically in buildbot with extra checkpoint

Details

    • Bug
    • Status: Closed (View Workflow)
    • Minor
    • Resolution: Duplicate
    • 10.0(EOL), 10.1(EOL), 10.2(EOL), 10.3(EOL), 10.4(EOL), 10.5
    • N/A
    • Tests
    • None

    Description

      http://buildbot.askmonty.org/buildbot/builders/bld-dan-debug/builds/2287/steps/test_1/logs/stdio

      binlog.binlog_xa_recover 'row,xtradb'    w2 [ fail ]
              Test ended at 2015-07-21 05:39:57
       
      CURRENT_TEST: binlog.binlog_xa_recover
      --- /opt/buildbot-slave/mariadb/dan_demeter1/build/mysql-test/suite/binlog/r/binlog_xa_recover.result	2015-07-21 00:53:48.708947231 +0300
      +++ /opt/buildbot-slave/mariadb/dan_demeter1/build/mysql-test/suite/binlog/r/binlog_xa_recover.reject	2015-07-21 05:39:57.781671954 +0300
      @@ -140,6 +140,7 @@
       master-bin.000004	#	Write_rows_v1	#	#	table_id: # flags: STMT_END_F
       master-bin.000004	#	Xid	#	#	COMMIT /* XID */
       master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000002
      +master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000003
       master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000004
       Now crash the server
       SET SESSION debug_dbug="+d,crash_commit_after_log";
       
      mysqltest: Result length mismatch
      

      Attachments

        Issue Links

          Activity

            elenst Elena Stepanova added a comment - - edited

            New occasion:
            http://buildbot.askmonty.org/buildbot/builders/bld-dan-debug/builds/3493/steps/test/logs/stdio

            binlog.binlog_xa_recover 'row,xtradb'    w2 [ fail ]
                    Test ended at 2016-07-25 01:33:19
             
            CURRENT_TEST: binlog.binlog_xa_recover
            --- /opt/buildbot-slave/mariadb/dan_demeter1/build/mysql-test/suite/binlog/r/binlog_xa_recover.result	2016-07-23 08:21:23.848826842 -0400
            +++ /opt/buildbot-slave/mariadb/dan_demeter1/build/mysql-test/suite/binlog/r/binlog_xa_recover.reject	2016-07-25 01:33:19.591643515 -0400
            @@ -140,6 +140,7 @@
             master-bin.000004	#	Write_rows_v1	#	#	table_id: # flags: STMT_END_F
             master-bin.000004	#	Xid	#	#	COMMIT /* XID */
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000002
            +master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000003
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000004
             Now crash the server
             SET SESSION debug_dbug="+d,crash_commit_after_log";
             
            mysqltest: Result length mismatch
            

            elenst Elena Stepanova added a comment - - edited New occasion: http://buildbot.askmonty.org/buildbot/builders/bld-dan-debug/builds/3493/steps/test/logs/stdio binlog.binlog_xa_recover 'row,xtradb' w2 [ fail ] Test ended at 2016-07-25 01:33:19   CURRENT_TEST: binlog.binlog_xa_recover --- /opt/buildbot-slave/mariadb/dan_demeter1/build/mysql-test/suite/binlog/r/binlog_xa_recover.result 2016-07-23 08:21:23.848826842 -0400 +++ /opt/buildbot-slave/mariadb/dan_demeter1/build/mysql-test/suite/binlog/r/binlog_xa_recover.reject 2016-07-25 01:33:19.591643515 -0400 @@ -140,6 +140,7 @@ master-bin.000004 # Write_rows_v1 # # table_id: # flags: STMT_END_F master-bin.000004 # Xid # # COMMIT /* XID */ master-bin.000004 # Binlog_checkpoint # # master-bin.000002 +master-bin.000004 # Binlog_checkpoint # # master-bin.000003 master-bin.000004 # Binlog_checkpoint # # master-bin.000004 Now crash the server SET SESSION debug_dbug="+d,crash_commit_after_log";   mysqltest: Result length mismatch
            alice Alice Sherepa added a comment -

            now on 10.3 http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/9501/steps/test_2/logs/stdio

            binlog.binlog_xa_recover 'innodb,row'    w3 [ fail ]
                    Test ended at 2017-09-06 23:38:14
             
            CURRENT_TEST: binlog.binlog_xa_recover
            --- /mnt/buildbot/build/mariadb-10.3.2/mysql-test/suite/binlog/r/binlog_xa_recover.result	2017-09-06 18:25:33.000000000 +0300
            +++ /mnt/buildbot/build/mariadb-10.3.2/mysql-test/suite/binlog/r/binlog_xa_recover.reject	2017-09-06 23:38:14.000000000 +0300
            @@ -168,6 +168,7 @@
             master-bin.000004	#	Write_rows_v1	#	#	table_id: # flags: STMT_END_F
             master-bin.000004	#	Xid	#	#	COMMIT /* XID */
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000002
            +master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000003
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000004
             Now crash the server
             SET SESSION debug_dbug="+d,crash_commit_after_log";
             
            mysqltest: Result length mismatch
            

            alice Alice Sherepa added a comment - now on 10.3 http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/9501/steps/test_2/logs/stdio binlog.binlog_xa_recover 'innodb,row' w3 [ fail ] Test ended at 2017-09-06 23:38:14   CURRENT_TEST: binlog.binlog_xa_recover --- /mnt/buildbot/build/mariadb-10.3.2/mysql-test/suite/binlog/r/binlog_xa_recover.result 2017-09-06 18:25:33.000000000 +0300 +++ /mnt/buildbot/build/mariadb-10.3.2/mysql-test/suite/binlog/r/binlog_xa_recover.reject 2017-09-06 23:38:14.000000000 +0300 @@ -168,6 +168,7 @@ master-bin.000004 # Write_rows_v1 # # table_id: # flags: STMT_END_F master-bin.000004 # Xid # # COMMIT /* XID */ master-bin.000004 # Binlog_checkpoint # # master-bin.000002 +master-bin.000004 # Binlog_checkpoint # # master-bin.000003 master-bin.000004 # Binlog_checkpoint # # master-bin.000004 Now crash the server SET SESSION debug_dbug="+d,crash_commit_after_log";   mysqltest: Result length mismatch
            alice Alice Sherepa added a comment -

            on 10.5 https://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/25220/steps/mtr_nm/logs/stdio

            binlog.binlog_xa_recover 'innodb,row'    w3 [ fail ]
                    Test ended at 2020-10-22 10:48:38
             
            CURRENT_TEST: binlog.binlog_xa_recover
            --- /mnt/buildbot/build/mariadb-10.5.7/mysql-test/suite/binlog/r/binlog_xa_recover.result	2020-10-22 08:20:48.000000000 -0400
            +++ /mnt/buildbot/build/mariadb-10.5.7/mysql-test/suite/binlog/r/binlog_xa_recover.reject	2020-10-22 10:48:37.999635806 -0400
            @@ -168,6 +168,7 @@
             master-bin.000004	#	Write_rows_v1	#	#	table_id: # flags: STMT_END_F
             master-bin.000004	#	Xid	#	#	COMMIT /* XID */
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000002
            +master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000003
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000004
             Now crash the server
             SET SESSION debug_dbug="+d,crash_commit_after_log";
             
            mysqltest: Result length mismatch
             
             - skipping '/mnt/buildbot/build/mariadb-10.5.7/mysql-test/var/3/log/binlog.binlog_xa_recover-innodb,row/'
             
            Retrying test binlog.binlog_xa_recover, attempt(2/3)...
            

            alice Alice Sherepa added a comment - on 10.5 https://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/25220/steps/mtr_nm/logs/stdio binlog.binlog_xa_recover 'innodb,row' w3 [ fail ] Test ended at 2020-10-22 10:48:38   CURRENT_TEST: binlog.binlog_xa_recover --- /mnt/buildbot/build/mariadb-10.5.7/mysql-test/suite/binlog/r/binlog_xa_recover.result 2020-10-22 08:20:48.000000000 -0400 +++ /mnt/buildbot/build/mariadb-10.5.7/mysql-test/suite/binlog/r/binlog_xa_recover.reject 2020-10-22 10:48:37.999635806 -0400 @@ -168,6 +168,7 @@ master-bin.000004 # Write_rows_v1 # # table_id: # flags: STMT_END_F master-bin.000004 # Xid # # COMMIT /* XID */ master-bin.000004 # Binlog_checkpoint # # master-bin.000002 +master-bin.000004 # Binlog_checkpoint # # master-bin.000003 master-bin.000004 # Binlog_checkpoint # # master-bin.000004 Now crash the server SET SESSION debug_dbug="+d,crash_commit_after_log";   mysqltest: Result length mismatch   - skipping '/mnt/buildbot/build/mariadb-10.5.7/mysql-test/var/3/log/binlog.binlog_xa_recover-innodb,row/'   Retrying test binlog.binlog_xa_recover, attempt(2/3)...
            alice Alice Sherepa added a comment -

            on 10.5 http://buildbot.askmonty.org/buildbot/builders/kvm-rpm-centos74-amd64-debug/builds/5378/steps/mtr_centos_mix/logs/stdio

            binlog.binlog_xa_recover 'innodb,row'    w3 [ fail ]
                    Test ended at 2020-12-03 10:40:10
             
            CURRENT_TEST: binlog.binlog_xa_recover
            --- /usr/share/mysql-test/suite/binlog/r/binlog_xa_recover.result	2020-12-03 06:04:24.000000000 +0000
            +++ /dev/shm/var/3/log/binlog_xa_recover.reject	2020-12-03 10:40:09.895357504 +0000
            @@ -155,6 +155,8 @@
             SET GLOBAL debug_dbug= @old_dbug;
             SET DEBUG_SYNC= "now SIGNAL con11_cont";
             connection con11;
            +Warnings:
            +Warning	1639	debug sync point wait timed out
             connection default;
             Checking that master-bin.000004 is the last binlog checkpoint
             include/show_binlog_events.inc
             
            mysqltest: Result length mismatch
            

            on 10.4 http://buildbot.askmonty.org/buildbot/builders/kvm-rpm-centos74-amd64-debug/builds/5252/steps/mtr/logs/stdio

            binlog.binlog_xa_recover 'innodb,row'    w3 [ fail ]
                    Test ended at 2020-11-11 08:06:58
             
            CURRENT_TEST: binlog.binlog_xa_recover
            --- /usr/share/mysql-test/suite/binlog/r/binlog_xa_recover.result	2020-11-11 05:40:29.000000000 +0000
            +++ /dev/shm/var/3/log/binlog_xa_recover.reject	2020-11-11 08:06:58.030269281 +0000
            @@ -150,11 +150,15 @@
             SET GLOBAL debug_dbug="+d,binlog_background_checkpoint_processed";
             SET DEBUG_SYNC= "now SIGNAL con12_cont";
             connection con12;
            +Warnings:
            +Warning	1639	debug sync point wait timed out
             connection default;
             SET DEBUG_SYNC= "now WAIT_FOR binlog_background_checkpoint_processed";
             SET GLOBAL debug_dbug= @old_dbug;
             SET DEBUG_SYNC= "now SIGNAL con11_cont";
             connection con11;
            +Warnings:
            +Warning	1639	debug sync point wait timed out
             connection default;
             Checking that master-bin.000004 is the last binlog checkpoint
             include/show_binlog_events.inc
            @@ -168,6 +172,7 @@
             master-bin.000004	#	Write_rows_v1	#	#	table_id: # flags: STMT_END_F
             master-bin.000004	#	Xid	#	#	COMMIT /* XID */
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000002
            +master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000003
             master-bin.000004	#	Binlog_checkpoint	#	#	master-bin.000004
             Now crash the server
             SET SESSION debug_dbug="+d,crash_commit_after_log";
             
            mysqltest: Result length mismatch
            

            alice Alice Sherepa added a comment - on 10.5 http://buildbot.askmonty.org/buildbot/builders/kvm-rpm-centos74-amd64-debug/builds/5378/steps/mtr_centos_mix/logs/stdio binlog.binlog_xa_recover 'innodb,row' w3 [ fail ] Test ended at 2020-12-03 10:40:10   CURRENT_TEST: binlog.binlog_xa_recover --- /usr/share/mysql-test/suite/binlog/r/binlog_xa_recover.result 2020-12-03 06:04:24.000000000 +0000 +++ /dev/shm/var/3/log/binlog_xa_recover.reject 2020-12-03 10:40:09.895357504 +0000 @@ -155,6 +155,8 @@ SET GLOBAL debug_dbug= @old_dbug; SET DEBUG_SYNC= "now SIGNAL con11_cont"; connection con11; +Warnings: +Warning 1639 debug sync point wait timed out connection default; Checking that master-bin.000004 is the last binlog checkpoint include/show_binlog_events.inc   mysqltest: Result length mismatch on 10.4 http://buildbot.askmonty.org/buildbot/builders/kvm-rpm-centos74-amd64-debug/builds/5252/steps/mtr/logs/stdio binlog.binlog_xa_recover 'innodb,row' w3 [ fail ] Test ended at 2020-11-11 08:06:58   CURRENT_TEST: binlog.binlog_xa_recover --- /usr/share/mysql-test/suite/binlog/r/binlog_xa_recover.result 2020-11-11 05:40:29.000000000 +0000 +++ /dev/shm/var/3/log/binlog_xa_recover.reject 2020-11-11 08:06:58.030269281 +0000 @@ -150,11 +150,15 @@ SET GLOBAL debug_dbug="+d,binlog_background_checkpoint_processed"; SET DEBUG_SYNC= "now SIGNAL con12_cont"; connection con12; +Warnings: +Warning 1639 debug sync point wait timed out connection default; SET DEBUG_SYNC= "now WAIT_FOR binlog_background_checkpoint_processed"; SET GLOBAL debug_dbug= @old_dbug; SET DEBUG_SYNC= "now SIGNAL con11_cont"; connection con11; +Warnings: +Warning 1639 debug sync point wait timed out connection default; Checking that master-bin.000004 is the last binlog checkpoint include/show_binlog_events.inc @@ -168,6 +172,7 @@ master-bin.000004 # Write_rows_v1 # # table_id: # flags: STMT_END_F master-bin.000004 # Xid # # COMMIT /* XID */ master-bin.000004 # Binlog_checkpoint # # master-bin.000002 +master-bin.000004 # Binlog_checkpoint # # master-bin.000003 master-bin.000004 # Binlog_checkpoint # # master-bin.000004 Now crash the server SET SESSION debug_dbug="+d,crash_commit_after_log";   mysqltest: Result length mismatch
            alice Alice Sherepa added a comment -

            the same bug as MDEV-12908, closing this one as there is some analysis from Svoj there.

            alice Alice Sherepa added a comment - the same bug as MDEV-12908 , closing this one as there is some analysis from Svoj there.

            People

              Elkin Andrei Elkin
              elenst Elena Stepanova
              Votes:
              0 Vote for this issue
              Watchers:
              2 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.