[MDEV-34001] Mismatched binlog positionining causing test failures in fedora:40 - Jira

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Major
Resolution: Duplicate
Affects Version/s: 10.4(EOL), 10.5
Fix Version/s: N/A
Component/s: Replication, Tests
Labels:
None

Description

mysqlbinlog_row_compressed and mysqlbinlog_stmt_compressed are now failing in fedora:40 as a result of mismatched result files and differing bin log positions. This does not occur on prior versions of fedora (tested with 38 and 39). The root cause for this is currently not known.

--- /server/mysql-test/main/mysqlbinlog_row_compressed.result	2024-04-25 18:24:09.640690050 +0000

+++ /server/mysql-test/main/mysqlbinlog_row_compressed.reject	2024-04-25 18:33:37.111015866 +0000

@@ -29,7 +29,7 @@

 /*!100001 SET @@session.server_id=1*//*!*/;

 /*!100001 SET @@session.gtid_seq_no=1*//*!*/;

 # at 371

-#<date> server id 1  end_log_pos 533 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+#<date> server id 1  end_log_pos 534 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 use `test`/*!*/;

 SET TIMESTAMP=X/*!*/;

 SET @@session.pseudo_thread_id=5/*!*/;

@@ -42,26 +42,26 @@

 SET @@session.collation_database=DEFAULT/*!*/;

 CREATE TABLE t1 (pk INT PRIMARY KEY, f1 INT, f2 INT, f3 TINYINT, f4 MEDIUMINT, f5 BIGINT, f6 INT, f7 INT, f8 char(1))

 /*!*/;

-# at 533

-#<date> server id 1  end_log_pos 575 CRC32 XXX 	GTID 0-1-2 ddl

+# at 534

+#<date> server id 1  end_log_pos 576 CRC32 XXX 	GTID 0-1-2 ddl

 /*!100001 SET @@session.gtid_seq_no=2*//*!*/;

-# at 575

-#<date> server id 1  end_log_pos 727 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 576

+#<date> server id 1  end_log_pos 730 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 CREATE TABLE t2 (pk INT PRIMARY KEY, f1 INT, f2 INT, f3 INT, f4 INT, f5 MEDIUMINT, f6 INT, f7 INT, f8 char(1))

 /*!*/;

-# at 727

-#<date> server id 1  end_log_pos 769 CRC32 XXX 	GTID 0-1-3

+# at 730

+#<date> server id 1  end_log_pos 772 CRC32 XXX 	GTID 0-1-3

 /*!100001 SET @@session.gtid_seq_no=3*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 769

-# at 843

-#<date> server id 1  end_log_pos 843 CRC32 XXX 	Annotate_rows:

+# at 772

+# at 846

+#<date> server id 1  end_log_pos 846 CRC32 XXX 	Annotate_rows:

 #Q> INSERT INTO t1 VALUES (10, 1, 2, 3, 4, 5, 6, 7, "")

-#<date> server id 1  end_log_pos 899 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

-# at 899

-#<date> server id 1  end_log_pos 967 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

+#<date> server id 1  end_log_pos 902 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

+# at 902

+#<date> server id 1  end_log_pos 972 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

 ### INSERT INTO `test`.`t1`

 ### SET

 ###   @1=10 /* INT meta=0 nullable=0 is_null=0 */

@@ -74,23 +74,23 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9='' /* STRING(1) meta=65025 nullable=1 is_null=0 */

 # Number of rows: 1

-# at 967

-#<date> server id 1  end_log_pos 1040 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 972

+#<date> server id 1  end_log_pos 1045 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1040

-#<date> server id 1  end_log_pos 1082 CRC32 XXX 	GTID 0-1-4

+# at 1045

+#<date> server id 1  end_log_pos 1087 CRC32 XXX 	GTID 0-1-4

 /*!100001 SET @@session.gtid_seq_no=4*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1082

-# at 1158

-#<date> server id 1  end_log_pos 1158 CRC32 XXX 	Annotate_rows:

+# at 1087

+# at 1163

+#<date> server id 1  end_log_pos 1163 CRC32 XXX 	Annotate_rows:

 #Q> INSERT INTO t1 VALUES (11, 1, 2, 3, 4, 5, 6, 7, NULL)

-#<date> server id 1  end_log_pos 1214 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

-# at 1214

-#<date> server id 1  end_log_pos 1281 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

+#<date> server id 1  end_log_pos 1219 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

+# at 1219

+#<date> server id 1  end_log_pos 1287 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

 ### INSERT INTO `test`.`t1`

 ### SET

 ###   @1=11 /* INT meta=0 nullable=0 is_null=0 */

@@ -103,23 +103,23 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9=NULL /* STRING(1) meta=65025 nullable=1 is_null=1 */

 # Number of rows: 1

-# at 1281

-#<date> server id 1  end_log_pos 1354 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 1287

+#<date> server id 1  end_log_pos 1360 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1354

-#<date> server id 1  end_log_pos 1396 CRC32 XXX 	GTID 0-1-5

+# at 1360

+#<date> server id 1  end_log_pos 1402 CRC32 XXX 	GTID 0-1-5

 /*!100001 SET @@session.gtid_seq_no=5*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1396

-# at 1474

-#<date> server id 1  end_log_pos 1474 CRC32 XXX 	Annotate_rows:

+# at 1402

+# at 1480

+#<date> server id 1  end_log_pos 1480 CRC32 XXX 	Annotate_rows:

 #Q> INSERT INTO t1 VALUES (12, 1, 2, 3, NULL, 5, 6, 7, "A")

-#<date> server id 1  end_log_pos 1530 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

-# at 1530

-#<date> server id 1  end_log_pos 1596 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

+#<date> server id 1  end_log_pos 1536 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

+# at 1536

+#<date> server id 1  end_log_pos 1606 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

 ### INSERT INTO `test`.`t1`

 ### SET

 ###   @1=12 /* INT meta=0 nullable=0 is_null=0 */

@@ -132,23 +132,23 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9='A' /* STRING(1) meta=65025 nullable=1 is_null=0 */

 # Number of rows: 1

-# at 1596

-#<date> server id 1  end_log_pos 1669 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 1606

+#<date> server id 1  end_log_pos 1679 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1669

-#<date> server id 1  end_log_pos 1711 CRC32 XXX 	GTID 0-1-6

+# at 1679

+#<date> server id 1  end_log_pos 1721 CRC32 XXX 	GTID 0-1-6

 /*!100001 SET @@session.gtid_seq_no=6*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1711

-# at 1786

-#<date> server id 1  end_log_pos 1786 CRC32 XXX 	Annotate_rows:

+# at 1721

+# at 1796

+#<date> server id 1  end_log_pos 1796 CRC32 XXX 	Annotate_rows:

 #Q> INSERT INTO t1 VALUES (13, 1, 2, 3, 0, 5, 6, 7, "A")

-#<date> server id 1  end_log_pos 1842 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

-# at 1842

-#<date> server id 1  end_log_pos 1909 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

+#<date> server id 1  end_log_pos 1852 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

+# at 1852

+#<date> server id 1  end_log_pos 1924 CRC32 XXX 	Write_compressed_rows: table id 32 flags: STMT_END_F

 ### INSERT INTO `test`.`t1`

 ### SET

 ###   @1=13 /* INT meta=0 nullable=0 is_null=0 */

@@ -161,23 +161,23 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9='A' /* STRING(1) meta=65025 nullable=1 is_null=0 */

 # Number of rows: 1

-# at 1909

-#<date> server id 1  end_log_pos 1982 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 1924

+#<date> server id 1  end_log_pos 1997 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1982

-#<date> server id 1  end_log_pos 2024 CRC32 XXX 	GTID 0-1-7

+# at 1997

+#<date> server id 1  end_log_pos 2039 CRC32 XXX 	GTID 0-1-7

 /*!100001 SET @@session.gtid_seq_no=7*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 2024

-# at 2078

-#<date> server id 1  end_log_pos 2078 CRC32 XXX 	Annotate_rows:

+# at 2039

+# at 2093

+#<date> server id 1  end_log_pos 2093 CRC32 XXX 	Annotate_rows:

 #Q> INSERT INTO t2 SELECT * FROM t1

-#<date> server id 1  end_log_pos 2134 CRC32 XXX 	Table_map: `test`.`t2` mapped to number num

-# at 2134

-#<date> server id 1  end_log_pos 2225 CRC32 XXX 	Write_compressed_rows: table id 33 flags: STMT_END_F

+#<date> server id 1  end_log_pos 2149 CRC32 XXX 	Table_map: `test`.`t2` mapped to number num

+# at 2149

+#<date> server id 1  end_log_pos 2240 CRC32 XXX 	Write_compressed_rows: table id 33 flags: STMT_END_F

 ### INSERT INTO `test`.`t2`

 ### SET

 ###   @1=10 /* INT meta=0 nullable=0 is_null=0 */

@@ -223,23 +223,23 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9='A' /* STRING(1) meta=65025 nullable=1 is_null=0 */

 # Number of rows: 4

-# at 2225

-#<date> server id 1  end_log_pos 2298 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 2240

+#<date> server id 1  end_log_pos 2313 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 2298

-#<date> server id 1  end_log_pos 2340 CRC32 XXX 	GTID 0-1-8

+# at 2313

+#<date> server id 1  end_log_pos 2355 CRC32 XXX 	GTID 0-1-8

 /*!100001 SET @@session.gtid_seq_no=8*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 2340

-# at 2406

-#<date> server id 1  end_log_pos 2406 CRC32 XXX 	Annotate_rows:

+# at 2355

+# at 2421

+#<date> server id 1  end_log_pos 2421 CRC32 XXX 	Annotate_rows:

 #Q> UPDATE t2 SET f4=5 WHERE f4>0 or f4 is NULL

-#<date> server id 1  end_log_pos 2462 CRC32 XXX 	Table_map: `test`.`t2` mapped to number num

-# at 2462

-#<date> server id 1  end_log_pos 2561 CRC32 XXX 	Update_compressed_rows: table id 33 flags: STMT_END_F

+#<date> server id 1  end_log_pos 2477 CRC32 XXX 	Table_map: `test`.`t2` mapped to number num

+# at 2477

+#<date> server id 1  end_log_pos 2578 CRC32 XXX 	Update_compressed_rows: table id 33 flags: STMT_END_F

 ### UPDATE `test`.`t2`

 ### WHERE

 ###   @1=10 /* INT meta=0 nullable=0 is_null=0 */

@@ -304,23 +304,23 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9='A' /* STRING(1) meta=65025 nullable=1 is_null=0 */

 # Number of rows: 3

-# at 2561

-#<date> server id 1  end_log_pos 2634 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 2578

+#<date> server id 1  end_log_pos 2651 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 2634

-#<date> server id 1  end_log_pos 2676 CRC32 XXX 	GTID 0-1-9

+# at 2651

+#<date> server id 1  end_log_pos 2693 CRC32 XXX 	GTID 0-1-9

 /*!100001 SET @@session.gtid_seq_no=9*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 2676

-# at 2713

-#<date> server id 1  end_log_pos 2713 CRC32 XXX 	Annotate_rows:

+# at 2693

+# at 2730

+#<date> server id 1  end_log_pos 2730 CRC32 XXX 	Annotate_rows:

 #Q> DELETE FROM t1

-#<date> server id 1  end_log_pos 2769 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

-# at 2769

-#<date> server id 1  end_log_pos 2861 CRC32 XXX 	Delete_compressed_rows: table id 32 flags: STMT_END_F

+#<date> server id 1  end_log_pos 2786 CRC32 XXX 	Table_map: `test`.`t1` mapped to number num

+# at 2786

+#<date> server id 1  end_log_pos 2882 CRC32 XXX 	Delete_compressed_rows: table id 32 flags: STMT_END_F

 ### DELETE FROM `test`.`t1`

 ### WHERE

 ###   @1=10 /* INT meta=0 nullable=0 is_null=0 */

@@ -366,23 +366,23 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9='A' /* STRING(1) meta=65025 nullable=1 is_null=0 */

 # Number of rows: 4

-# at 2861

-#<date> server id 1  end_log_pos 2934 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 2882

+#<date> server id 1  end_log_pos 2955 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 2934

-#<date> server id 1  end_log_pos 2976 CRC32 XXX 	GTID 0-1-10

+# at 2955

+#<date> server id 1  end_log_pos 2997 CRC32 XXX 	GTID 0-1-10

 /*!100001 SET @@session.gtid_seq_no=10*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 2976

-# at 3013

-#<date> server id 1  end_log_pos 3013 CRC32 XXX 	Annotate_rows:

+# at 2997

+# at 3034

+#<date> server id 1  end_log_pos 3034 CRC32 XXX 	Annotate_rows:

 #Q> DELETE FROM t2

-#<date> server id 1  end_log_pos 3069 CRC32 XXX 	Table_map: `test`.`t2` mapped to number num

-# at 3069

-#<date> server id 1  end_log_pos 3154 CRC32 XXX 	Delete_compressed_rows: table id 33 flags: STMT_END_F

+#<date> server id 1  end_log_pos 3090 CRC32 XXX 	Table_map: `test`.`t2` mapped to number num

+# at 3090

+#<date> server id 1  end_log_pos 3178 CRC32 XXX 	Delete_compressed_rows: table id 33 flags: STMT_END_F

 ### DELETE FROM `test`.`t2`

 ### WHERE

 ###   @1=10 /* INT meta=0 nullable=0 is_null=0 */

@@ -428,13 +428,13 @@

 ###   @8=7 /* INT meta=0 nullable=1 is_null=0 */

 ###   @9='A' /* STRING(1) meta=65025 nullable=1 is_null=0 */

 # Number of rows: 4

-# at 3154

-#<date> server id 1  end_log_pos 3227 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 3178

+#<date> server id 1  end_log_pos 3251 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 3227

-#<date> server id 1  end_log_pos 3275 CRC32 XXX 	Rotate to master-bin.000002  pos: 4

+# at 3251

+#<date> server id 1  end_log_pos 3299 CRC32 XXX 	Rotate to master-bin.000002  pos: 4

 DELIMITER ;

 # End of log file

 ROLLBACK /* added by mysqlbinlog */;

Result content mismatch

 - saving '/server/builddir/mysql-test/var/log/main.mysqlbinlog_row_compressed/' to '/server/builddir/mysql-test/var/log/main.mysqlbinlog_row_compressed/'

--------------------------------------------------------------------------

The servers were restarted 0 times

Spent 0.000 of 8 seconds executing testcases

Failure: Failed 1/1 tests, 0.00% were successful.

Failing test(s): main.mysqlbinlog_row_compressed

The log files in var/log may give you some hint of what went wrong.

If you want to report this error, please read first the documentation

at http://dev.mysql.com/doc/mysql/en/mysql-test-suite.html

mysql-test-run: *** ERROR: there were failing test cases

CURRENT_TEST: main.mysqlbinlog_stmt_compressed

--- /server/mysql-test/main/mysqlbinlog_stmt_compressed.result	2024-04-25 18:24:09.640690050 +0000

+++ /server/mysql-test/main/mysqlbinlog_stmt_compressed.reject	2024-04-25 18:33:57.991027763 +0000

@@ -29,7 +29,7 @@

 /*!100001 SET @@session.server_id=1*//*!*/;

 /*!100001 SET @@session.gtid_seq_no=1*//*!*/;

 # at 371

-#<date> server id 1  end_log_pos 533 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+#<date> server id 1  end_log_pos 534 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 use `test`/*!*/;

 SET TIMESTAMP=X/*!*/;

 SET @@session.pseudo_thread_id=5/*!*/;

@@ -42,136 +42,136 @@

 SET @@session.collation_database=DEFAULT/*!*/;

 CREATE TABLE t1 (pk INT PRIMARY KEY, f1 INT, f2 INT, f3 TINYINT, f4 MEDIUMINT, f5 BIGINT, f6 INT, f7 INT, f8 char(1))

 /*!*/;

-# at 533

-#<date> server id 1  end_log_pos 575 CRC32 XXX 	GTID 0-1-2 ddl

+# at 534

+#<date> server id 1  end_log_pos 576 CRC32 XXX 	GTID 0-1-2 ddl

 /*!100001 SET @@session.gtid_seq_no=2*//*!*/;

-# at 575

-#<date> server id 1  end_log_pos 727 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 576

+#<date> server id 1  end_log_pos 730 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 CREATE TABLE t2 (pk INT PRIMARY KEY, f1 INT, f2 INT, f3 INT, f4 INT, f5 MEDIUMINT, f6 INT, f7 INT, f8 char(1))

 /*!*/;

-# at 727

-#<date> server id 1  end_log_pos 769 CRC32 XXX 	GTID 0-1-3

+# at 730

+#<date> server id 1  end_log_pos 772 CRC32 XXX 	GTID 0-1-3

 /*!100001 SET @@session.gtid_seq_no=3*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 769

-#<date> server id 1  end_log_pos 897 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 772

+#<date> server id 1  end_log_pos 900 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 INSERT INTO t1 VALUES (10, 1, 2, 3, 4, 5, 6, 7, "")

 /*!*/;

-# at 897

-#<date> server id 1  end_log_pos 970 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 900

+#<date> server id 1  end_log_pos 973 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 970

-#<date> server id 1  end_log_pos 1012 CRC32 XXX 	GTID 0-1-4

+# at 973

+#<date> server id 1  end_log_pos 1015 CRC32 XXX 	GTID 0-1-4

 /*!100001 SET @@session.gtid_seq_no=4*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1012

-#<date> server id 1  end_log_pos 1140 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 1015

+#<date> server id 1  end_log_pos 1145 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 INSERT INTO t1 VALUES (11, 1, 2, 3, 4, 5, 6, 7, NULL)

 /*!*/;

-# at 1140

-#<date> server id 1  end_log_pos 1213 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 1145

+#<date> server id 1  end_log_pos 1218 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1213

-#<date> server id 1  end_log_pos 1255 CRC32 XXX 	GTID 0-1-5

+# at 1218

+#<date> server id 1  end_log_pos 1260 CRC32 XXX 	GTID 0-1-5

 /*!100001 SET @@session.gtid_seq_no=5*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1255

-#<date> server id 1  end_log_pos 1385 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 1260

+#<date> server id 1  end_log_pos 1392 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 INSERT INTO t1 VALUES (12, 1, 2, 3, NULL, 5, 6, 7, "A")

 /*!*/;

-# at 1385

-#<date> server id 1  end_log_pos 1458 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 1392

+#<date> server id 1  end_log_pos 1465 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1458

-#<date> server id 1  end_log_pos 1500 CRC32 XXX 	GTID 0-1-6

+# at 1465

+#<date> server id 1  end_log_pos 1507 CRC32 XXX 	GTID 0-1-6

 /*!100001 SET @@session.gtid_seq_no=6*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1500

-#<date> server id 1  end_log_pos 1627 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 1507

+#<date> server id 1  end_log_pos 1636 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 INSERT INTO t1 VALUES (13, 1, 2, 3, 0, 5, 6, 7, "A")

 /*!*/;

-# at 1627

-#<date> server id 1  end_log_pos 1700 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 1636

+#<date> server id 1  end_log_pos 1709 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1700

-#<date> server id 1  end_log_pos 1742 CRC32 XXX 	GTID 0-1-7

+# at 1709

+#<date> server id 1  end_log_pos 1751 CRC32 XXX 	GTID 0-1-7

 /*!100001 SET @@session.gtid_seq_no=7*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1742

-#<date> server id 1  end_log_pos 1850 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 1751

+#<date> server id 1  end_log_pos 1859 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 INSERT INTO t2 SELECT * FROM t1

 /*!*/;

-# at 1850

-#<date> server id 1  end_log_pos 1923 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 1859

+#<date> server id 1  end_log_pos 1932 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 1923

-#<date> server id 1  end_log_pos 1965 CRC32 XXX 	GTID 0-1-8

+# at 1932

+#<date> server id 1  end_log_pos 1974 CRC32 XXX 	GTID 0-1-8

 /*!100001 SET @@session.gtid_seq_no=8*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 1965

-#<date> server id 1  end_log_pos 2082 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 1974

+#<date> server id 1  end_log_pos 2094 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 UPDATE t2 SET f4=5 WHERE f4>0 or f4 is NULL

 /*!*/;

-# at 2082

-#<date> server id 1  end_log_pos 2155 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 2094

+#<date> server id 1  end_log_pos 2167 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 2155

-#<date> server id 1  end_log_pos 2197 CRC32 XXX 	GTID 0-1-9

+# at 2167

+#<date> server id 1  end_log_pos 2209 CRC32 XXX 	GTID 0-1-9

 /*!100001 SET @@session.gtid_seq_no=9*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 2197

-#<date> server id 1  end_log_pos 2288 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 2209

+#<date> server id 1  end_log_pos 2300 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 DELETE FROM t1

 /*!*/;

-# at 2288

-#<date> server id 1  end_log_pos 2361 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 2300

+#<date> server id 1  end_log_pos 2373 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 2361

-#<date> server id 1  end_log_pos 2403 CRC32 XXX 	GTID 0-1-10

+# at 2373

+#<date> server id 1  end_log_pos 2415 CRC32 XXX 	GTID 0-1-10

 /*!100001 SET @@session.gtid_seq_no=10*//*!*/;

 START TRANSACTION

 /*!*/;

-# at 2403

-#<date> server id 1  end_log_pos 2494 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

+# at 2415

+#<date> server id 1  end_log_pos 2506 CRC32 XXX 	Query_compressedthread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 DELETE FROM t2

 /*!*/;

-# at 2494

-#<date> server id 1  end_log_pos 2567 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

+# at 2506

+#<date> server id 1  end_log_pos 2579 CRC32 XXX 	Query	thread_id=5	exec_time=x	error_code=0

 SET TIMESTAMP=X/*!*/;

 COMMIT

 /*!*/;

-# at 2567

-#<date> server id 1  end_log_pos 2615 CRC32 XXX 	Rotate to master-bin.000002  pos: 4

+# at 2579

+#<date> server id 1  end_log_pos 2627 CRC32 XXX 	Rotate to master-bin.000002  pos: 4

 DELIMITER ;

 # End of log file

 ROLLBACK /* added by mysqlbinlog */;

Result content mismatch

 - saving '/server/builddir/mysql-test/var/log/main.mysqlbinlog_stmt_compressed/' to '/server/builddir/mysql-test/var/log/main.mysqlbinlog_stmt_compressed/'

--------------------------------------------------------------------------

The servers were restarted 0 times

Spent 0.000 of 8 seconds executing testcases

Failure: Failed 1/1 tests, 0.00% were successful.

Failing test(s): main.mysqlbinlog_stmt_compressed

Attachments

Issue Links

duplicates

MDEV-22964 main.mysqlbinlog_row_compressed fails with wrong result

Closed

Activity

Ascending order - Click to sort in descending order

Kristian Nielsen added a comment - 2024-04-26 09:39

I'll just mention that similar phenomenon has been observed recently in other places, where use of a different compression lib can cause slight difference in the size of compressed events.

See for example this commit, which looks relevant from a cursory glance:

commit 75488a57f23b99f5d531366c4717e2ce63cebd7e

Author: Sergei Golubchik <serg@mariadb.org>

Date:   Sat Apr 20 00:17:30 2024 +0200

    archive.archive and main.mysqlbinlog_row_compressed

    fixes for zlib-ng

- Kristian.

Kristian Nielsen added a comment - 2024-04-26 09:39 I'll just mention that similar phenomenon has been observed recently in other places, where use of a different compression lib can cause slight difference in the size of compressed events. See for example this commit, which looks relevant from a cursory glance: commit 75488a57f23b99f5d531366c4717e2ce63cebd7e Author: Sergei Golubchik <serg@mariadb.org> Date: Sat Apr 20 00:17:30 2024 +0200 archive.archive and main.mysqlbinlog_row_compressed fixes for zlib-ng - Kristian.

Daniel Black added a comment - 2024-12-13 00:53

Backported the above patch.

Daniel Black added a comment - 2024-12-13 00:53 Backported the above patch.

People

Assignee:: Unassigned

Reporter:: Anson

Votes:: 0 Vote for this issue

Watchers:: 3 Start watching this issue

Dates

Created:: 2024-04-25 21:38

Updated:: 2024-12-13 00:53

Resolved:: 2024-12-13 00:53

Git Integration

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

MariaDB Server