[MDEV-8517] binlog.binlog_xa_recover fails sporadically in buildbot with extra checkpoint Created: 2015-07-21  Updated: 2021-01-12  Resolved: 2021-01-12

Status: Closed
Project: MariaDB Server
Component/s: Tests
Affects Version/s: 10.0, 10.1, 10.2, 10.3, 10.4, 10.5
Fix Version/s: N/A

Type: Bug Priority: Minor
Reporter: Elena Stepanova Assignee: Andrei Elkin
Resolution: Duplicate Votes: 0
Labels: None

Issue Links:
Duplicate
is duplicated by MDEV-12908 binlog_encryption.binlog_xa_recover, ... Closed
Relates
relates to MDEV-7069 Fix buildbot failures in main server ... Stalled

 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



 Comments   
Comment by Elena Stepanova [ 2016-07-31 ]

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

Comment by Alice Sherepa [ 2017-09-07 ]

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

Comment by Alice Sherepa [ 2020-11-05 ]

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)...

Comment by Alice Sherepa [ 2020-12-04 ]

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

Comment by Alice Sherepa [ 2021-01-12 ]

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

Generated at Thu Feb 08 07:27:45 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.