[MDEV-30676] rpl.parallel_backup* tests sometimes fail with Result length mismatch - Jira

Angelique Sklavounos (Inactive) created issue - 2023-02-17 13:54

Angelique Sklavounos (Inactive) made changes - 2023-02-17 19:50

Field	Original Value	New Value
Fix Version/s		10.6 [ 24028 ]
Affects Version/s		10.6 [ 24028 ]
Affects Version/s		10.8 [ 26121 ]

Angelique Sklavounos (Inactive) made changes - 2023-02-17 19:51

Description

{noformat:title=10.6 67a6ad0a4}
rpl.parallel_backup_lsu_off 'innodb,mix' w14 [ fail ]
Test ended at 2023-02-16 09:31:38

CURRENT_TEST: rpl.parallel_backup_lsu_off
--- /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.result 2023-02-16 08:50:33.000000000 +0000
+++ /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.reject 2023-02-16 09:31:37.703046027 +0000
@@ -187,6 +187,18 @@
BACKUP STAGE START;
BACKUP STAGE BLOCK_COMMIT;
connection aux_slave;
+Timeout in wait_condition.inc for SELECT COUNT(*) = 0 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit"
+Id User Host db Command Time State Info Progress
+5 root localhost:56620 test Sleep 36 NULL 0.000
+6 root localhost:56626 test Sleep 37 NULL 0.000
+9 root localhost:56628 test Sleep 32 NULL 0.000
+10 root localhost:56636 test Sleep 36 NULL 0.000
+11 root localhost:56640 test Execute 0 starting show full processlist 0.000
+16 root localhost:56656 test Sleep 32 NULL 0.000
+33 system user NULL Slave_IO 32 Waiting for master to send event NULL 0.000
+35 system user NULL Slave_worker 32 Waiting for backup lock NULL 0.000
+36 system user test Slave_worker 32 Waiting for prior transaction to commit XA COMMIT X'31',X'',1 0.000
+34 system user NULL Slave_SQL 32 Slave has read all relay log; waiting for more updates NULL 0.000
ROLLBACK;
connection backup_slave;
BACKUP STAGE END;

mysqltest: Result length mismatch
{noformat}

Seems to mostly happen on s390x.

https://buildbot.mariadb.org/#/builders/236/builds/8645
{noformat:title=10.6 67a6ad0a4}
rpl.parallel_backup_lsu_off 'innodb,mix' w14 [ fail ]
Test ended at 2023-02-16 09:31:38

CURRENT_TEST: rpl.parallel_backup_lsu_off
--- /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.result 2023-02-16 08:50:33.000000000 +0000
+++ /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.reject 2023-02-16 09:31:37.703046027 +0000
@@ -187,6 +187,18 @@
BACKUP STAGE START;
BACKUP STAGE BLOCK_COMMIT;
connection aux_slave;
+Timeout in wait_condition.inc for SELECT COUNT(*) = 0 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit"
+Id User Host db Command Time State Info Progress
+5 root localhost:56620 test Sleep 36 NULL 0.000
+6 root localhost:56626 test Sleep 37 NULL 0.000
+9 root localhost:56628 test Sleep 32 NULL 0.000
+10 root localhost:56636 test Sleep 36 NULL 0.000
+11 root localhost:56640 test Execute 0 starting show full processlist 0.000
+16 root localhost:56656 test Sleep 32 NULL 0.000
+33 system user NULL Slave_IO 32 Waiting for master to send event NULL 0.000
+35 system user NULL Slave_worker 32 Waiting for backup lock NULL 0.000
+36 system user test Slave_worker 32 Waiting for prior transaction to commit XA COMMIT X'31',X'',1 0.000
+34 system user NULL Slave_SQL 32 Slave has read all relay log; waiting for more updates NULL 0.000
ROLLBACK;
connection backup_slave;
BACKUP STAGE END;

mysqltest: Result length mismatch
{noformat}

Seems to mostly happen on s390x.

Angelique Sklavounos (Inactive) made changes - 2023-02-17 20:23

Summary

[Draft] rpl.parallel_backup_lsu_off sometimes fails with Result length mismatch

[Draft] rpl.parallel_backup* tests sometimes fail with Result length mismatch

Angelique Sklavounos (Inactive) made changes - 2023-02-17 20:25

Description

https://buildbot.mariadb.org/#/builders/236/builds/8645
{noformat:title=10.6 67a6ad0a4}
rpl.parallel_backup_lsu_off 'innodb,mix' w14 [ fail ]
Test ended at 2023-02-16 09:31:38

CURRENT_TEST: rpl.parallel_backup_lsu_off
--- /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.result 2023-02-16 08:50:33.000000000 +0000
+++ /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.reject 2023-02-16 09:31:37.703046027 +0000
@@ -187,6 +187,18 @@
BACKUP STAGE START;
BACKUP STAGE BLOCK_COMMIT;
connection aux_slave;
+Timeout in wait_condition.inc for SELECT COUNT(*) = 0 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit"
+Id User Host db Command Time State Info Progress
+5 root localhost:56620 test Sleep 36 NULL 0.000
+6 root localhost:56626 test Sleep 37 NULL 0.000
+9 root localhost:56628 test Sleep 32 NULL 0.000
+10 root localhost:56636 test Sleep 36 NULL 0.000
+11 root localhost:56640 test Execute 0 starting show full processlist 0.000
+16 root localhost:56656 test Sleep 32 NULL 0.000
+33 system user NULL Slave_IO 32 Waiting for master to send event NULL 0.000
+35 system user NULL Slave_worker 32 Waiting for backup lock NULL 0.000
+36 system user test Slave_worker 32 Waiting for prior transaction to commit XA COMMIT X'31',X'',1 0.000
+34 system user NULL Slave_SQL 32 Slave has read all relay log; waiting for more updates NULL 0.000
ROLLBACK;
connection backup_slave;
BACKUP STAGE END;

mysqltest: Result length mismatch
{noformat}

Seems to mostly happen on s390x.

https://buildbot.mariadb.org/#/builders/236/builds/8645
{noformat:title=10.6 67a6ad0a4}
rpl.parallel_backup_lsu_off 'innodb,mix' w14 [ fail ]
Test ended at 2023-02-16 09:31:38

CURRENT_TEST: rpl.parallel_backup_lsu_off
--- /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.result 2023-02-16 08:50:33.000000000 +0000
+++ /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.reject 2023-02-16 09:31:37.703046027 +0000
@@ -187,6 +187,18 @@
BACKUP STAGE START;
BACKUP STAGE BLOCK_COMMIT;
connection aux_slave;
+Timeout in wait_condition.inc for SELECT COUNT(*) = 0 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit"
+Id User Host db Command Time State Info Progress
+5 root localhost:56620 test Sleep 36 NULL 0.000
+6 root localhost:56626 test Sleep 37 NULL 0.000
+9 root localhost:56628 test Sleep 32 NULL 0.000
+10 root localhost:56636 test Sleep 36 NULL 0.000
+11 root localhost:56640 test Execute 0 starting show full processlist 0.000
+16 root localhost:56656 test Sleep 32 NULL 0.000
+33 system user NULL Slave_IO 32 Waiting for master to send event NULL 0.000
+35 system user NULL Slave_worker 32 Waiting for backup lock NULL 0.000
+36 system user test Slave_worker 32 Waiting for prior transaction to commit XA COMMIT X'31',X'',1 0.000
+34 system user NULL Slave_SQL 32 Slave has read all relay log; waiting for more updates NULL 0.000
ROLLBACK;
connection backup_slave;
BACKUP STAGE END;

mysqltest: Result length mismatch
{noformat}

Tests affected: {{rpl.parallel_backup_lsu_off}}, {{rpl.parallel_backup}}, {{rpl.parallel_backup_slave_binlog_off}}

Seems to mostly happen on s390x.

Angelique Sklavounos (Inactive) made changes - 2023-02-17 20:26

Description

https://buildbot.mariadb.org/#/builders/236/builds/8645
{noformat:title=10.6 67a6ad0a4}
rpl.parallel_backup_lsu_off 'innodb,mix' w14 [ fail ]
Test ended at 2023-02-16 09:31:38

CURRENT_TEST: rpl.parallel_backup_lsu_off
--- /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.result 2023-02-16 08:50:33.000000000 +0000
+++ /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.reject 2023-02-16 09:31:37.703046027 +0000
@@ -187,6 +187,18 @@
BACKUP STAGE START;
BACKUP STAGE BLOCK_COMMIT;
connection aux_slave;
+Timeout in wait_condition.inc for SELECT COUNT(*) = 0 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit"
+Id User Host db Command Time State Info Progress
+5 root localhost:56620 test Sleep 36 NULL 0.000
+6 root localhost:56626 test Sleep 37 NULL 0.000
+9 root localhost:56628 test Sleep 32 NULL 0.000
+10 root localhost:56636 test Sleep 36 NULL 0.000
+11 root localhost:56640 test Execute 0 starting show full processlist 0.000
+16 root localhost:56656 test Sleep 32 NULL 0.000
+33 system user NULL Slave_IO 32 Waiting for master to send event NULL 0.000
+35 system user NULL Slave_worker 32 Waiting for backup lock NULL 0.000
+36 system user test Slave_worker 32 Waiting for prior transaction to commit XA COMMIT X'31',X'',1 0.000
+34 system user NULL Slave_SQL 32 Slave has read all relay log; waiting for more updates NULL 0.000
ROLLBACK;
connection backup_slave;
BACKUP STAGE END;

mysqltest: Result length mismatch
{noformat}

Tests affected: {{rpl.parallel_backup_lsu_off}}, {{rpl.parallel_backup}}, {{rpl.parallel_backup_slave_binlog_off}}

Seems to mostly happen on s390x.

https://buildbot.mariadb.org/#/builders/236/builds/8645
{noformat:title=10.6 67a6ad0a4}
rpl.parallel_backup_lsu_off 'innodb,mix' w14 [ fail ]
Test ended at 2023-02-16 09:31:38

CURRENT_TEST: rpl.parallel_backup_lsu_off
--- /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.result 2023-02-16 08:50:33.000000000 +0000
+++ /home/buildbot/amd64-ubuntu-2004-fulltest/build/mysql-test/suite/rpl/r/parallel_backup_lsu_off.reject 2023-02-16 09:31:37.703046027 +0000
@@ -187,6 +187,18 @@
BACKUP STAGE START;
BACKUP STAGE BLOCK_COMMIT;
connection aux_slave;
+Timeout in wait_condition.inc for SELECT COUNT(*) = 0 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit"
+Id User Host db Command Time State Info Progress
+5 root localhost:56620 test Sleep 36 NULL 0.000
+6 root localhost:56626 test Sleep 37 NULL 0.000
+9 root localhost:56628 test Sleep 32 NULL 0.000
+10 root localhost:56636 test Sleep 36 NULL 0.000
+11 root localhost:56640 test Execute 0 starting show full processlist 0.000
+16 root localhost:56656 test Sleep 32 NULL 0.000
+33 system user NULL Slave_IO 32 Waiting for master to send event NULL 0.000
+35 system user NULL Slave_worker 32 Waiting for backup lock NULL 0.000
+36 system user test Slave_worker 32 Waiting for prior transaction to commit XA COMMIT X'31',X'',1 0.000
+34 system user NULL Slave_SQL 32 Slave has read all relay log; waiting for more updates NULL 0.000
ROLLBACK;
connection backup_slave;
BACKUP STAGE END;

mysqltest: Result length mismatch
{noformat}

+Tests affected+
rpl.parallel_backup_lsu_off
rpl.parallel_backup
rpl.parallel_backup_slave_binlog_off

Seems to mostly happen on s390x.

Angelique Sklavounos (Inactive) made changes - 2023-02-17 20:26

Affects Version/s

10.5 [ 23123 ]

Angelique Sklavounos (Inactive) made changes - 2023-02-17 20:26

Fix Version/s

10.5 [ 23123 ]

Angelique Sklavounos (Inactive) made changes - 2023-02-17 21:45

Component/s		Replication [ 10100 ]
Component/s		Tests [ 10800 ]
Fix Version/s		10.8 [ 26121 ]
Assignee	Angelique Sklavounos [ JIRAUSER50741 ]	Andrei Elkin [ elkin ]
Summary	[Draft] rpl.parallel_backup* tests sometimes fail with Result length mismatch	rpl.parallel_backup* tests sometimes fail with Result length mismatch

Angelique Sklavounos (Inactive) added a comment - 2023-02-17 22:00

Output can vary:
https://buildbot.mariadb.org/#/builders/192/builds/16121

rpl.parallel_backup 'innodb,mix'         w29 [ fail ]

        Test ended at 2023-01-31 14:55:24

CURRENT_TEST: rpl.parallel_backup

--- /buildbot/amd64-ubuntu-1804-valgrind/build/mysql-test/suite/rpl/r/parallel_backup.result	2023-01-31 14:43:00.000000000 +0000

+++ /buildbot/amd64-ubuntu-1804-valgrind/build/mysql-test/suite/rpl/r/parallel_backup.reject	2023-01-31 14:55:20.151212882 +0000

@@ -176,6 +176,15 @@

 SET @@global.slave_transaction_retries=0;

 include/start_slave.inc

 connection aux_slave;

+Timeout in wait_condition.inc for SELECT COUNT(*) = 1 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit"

+Id	User	Host	db	Command	Time	State	Info	Progress

+5	root	localhost:37062	test	Sleep	62		NULL	0.000

+6	root	localhost:37070	test	Sleep	63		NULL	0.000

+9	root	localhost:37072	test	Sleep	41		NULL	0.000

+10	root	localhost:37080	test	Sleep	61		NULL	0.000

+11	root	localhost:43078	test	Query	0	starting	show full processlist	0.000

+16	root	localhost:43086	test	Sleep	46		NULL	0.000

+33	system user		NULL	Slave_IO	44	Waiting for master to send event	NULL	0.000

 # Xid '1' must be in the output:

 XA RECOVER;

 formatID	gtrid_length	bqual_length	data

@@ -185,6 +194,15 @@

 BACKUP STAGE BLOCK_COMMIT;

 connection aux_slave;

 ROLLBACK;

+Timeout in wait_condition.inc for SELECT COUNT(*) = 1 FROM information_schema.processlist WHERE state = "Waiting for backup lock"

+Id	User	Host	db	Command	Time	State	Info	Progress

+5	root	localhost:37062	test	Sleep	104		NULL	0.000

+6	root	localhost:37070	test	Sleep	105		NULL	0.000

+9	root	localhost:37072	test	Sleep	83		NULL	0.000

+10	root	localhost:37080	test	Sleep	103		NULL	0.000

+11	root	localhost:43078	test	Query	0	starting	show full processlist	0.000

+16	root	localhost:43086	test	Sleep	41		NULL	0.000

+33	system user		NULL	Slave_IO	85	Waiting for master to send event	NULL	0.000

 connection backup_slave;

 BACKUP STAGE END;

 connection slave;

mysqltest: Result length mismatch

Angelique Sklavounos (Inactive) added a comment - 2023-02-17 22:00 Output can vary: https://buildbot.mariadb.org/#/builders/192/builds/16121 rpl.parallel_backup 'innodb,mix' w29 [ fail ] Test ended at 2023-01-31 14:55:24 CURRENT_TEST: rpl.parallel_backup --- /buildbot/amd64-ubuntu-1804-valgrind/build/mysql-test/suite/rpl/r/parallel_backup.result 2023-01-31 14:43:00.000000000 +0000 +++ /buildbot/amd64-ubuntu-1804-valgrind/build/mysql-test/suite/rpl/r/parallel_backup.reject 2023-01-31 14:55:20.151212882 +0000 @@ -176,6 +176,15 @@ SET @@global.slave_transaction_retries=0; include/start_slave.inc connection aux_slave; +Timeout in wait_condition.inc for SELECT COUNT(*) = 1 FROM information_schema.processlist WHERE state = "Waiting for prior transaction to commit" +Id User Host db Command Time State Info Progress +5 root localhost:37062 test Sleep 62 NULL 0.000 +6 root localhost:37070 test Sleep 63 NULL 0.000 +9 root localhost:37072 test Sleep 41 NULL 0.000 +10 root localhost:37080 test Sleep 61 NULL 0.000 +11 root localhost:43078 test Query 0 starting show full processlist 0.000 +16 root localhost:43086 test Sleep 46 NULL 0.000 +33 system user NULL Slave_IO 44 Waiting for master to send event NULL 0.000 # Xid '1' must be in the output: XA RECOVER; formatID gtrid_length bqual_length data @@ -185,6 +194,15 @@ BACKUP STAGE BLOCK_COMMIT; connection aux_slave; ROLLBACK; +Timeout in wait_condition.inc for SELECT COUNT(*) = 1 FROM information_schema.processlist WHERE state = "Waiting for backup lock" +Id User Host db Command Time State Info Progress +5 root localhost:37062 test Sleep 104 NULL 0.000 +6 root localhost:37070 test Sleep 105 NULL 0.000 +9 root localhost:37072 test Sleep 83 NULL 0.000 +10 root localhost:37080 test Sleep 103 NULL 0.000 +11 root localhost:43078 test Query 0 starting show full processlist 0.000 +16 root localhost:43086 test Sleep 41 NULL 0.000 +33 system user NULL Slave_IO 85 Waiting for master to send event NULL 0.000 connection backup_slave; BACKUP STAGE END; connection slave; mysqltest: Result length mismatch

Angelique Sklavounos (Inactive) made changes - 2023-04-11 18:16

Affects Version/s		10.9 [ 26905 ]
Affects Version/s		10.10 [ 27530 ]
Affects Version/s		10.11 [ 27614 ]
Affects Version/s		11.0 [ 28320 ]
Affects Version/s		11.1 [ 28549 ]

Angelique Sklavounos (Inactive) made changes - 2023-04-11 18:17

Fix Version/s		10.9 [ 26905 ]
Fix Version/s		10.10 [ 27530 ]
Fix Version/s		10.11 [ 27614 ]
Fix Version/s		11.0 [ 28320 ]
Fix Version/s		11.1 [ 28549 ]

Julien Fritsch made changes - 2023-04-27 14:55

Fix Version/s

10.8 [ 26121 ]

Otto Kekäläinen added a comment - 2023-10-09 18:18

Seen in https://launchpadlibrarian.net/691101423/buildlog_ubuntu-mantic-riscv64.mariadb_1%3A10.11.5-3~bpo23.10.1~1696830433.49186426d72+feature.re.enable.all.tests_BUILDING.txt.gz for MariaDB 10.11.5 on riscv64

rpl.parallel_backup_slave_binlog_off 'innodb,mix' w5 [ fail ]

        Test ended at 2023-10-09 11:21:39

CURRENT_TEST: rpl.parallel_backup_slave_binlog_off

--- /<<PKGBUILDDIR>>/mysql-test/suite/rpl/r/parallel_backup_slave_binlog_off.result	2023-08-11 06:35:40.000000000 +0000

+++ /<<PKGBUILDDIR>>/mysql-test/suite/rpl/r/parallel_backup_slave_binlog_off.reject	2023-10-09 11:21:35.273185963 +0000

@@ -145,6 +145,15 @@

 BACKUP STAGE BLOCK_COMMIT;

 connection aux_slave;

 ROLLBACK;

+Timeout in wait_condition.inc for SELECT COUNT(*) = 1 FROM information_schema.processlist WHERE state = "Waiting for backup lock"

+Id	User	Host	db	Command	Time	State	Info	Progress

+4	root	localhost:59878	test	Sleep	59		NULL	0.000

+5	root	localhost:59880	test	Sleep	61		NULL	0.000

+8	root	localhost:59882	test	Sleep	46		NULL	0.000

+9	root	localhost:59884	test	Sleep	59		NULL	0.000

+10	root	localhost:59886	test	Query	0	starting	show full processlist	0.000

+15	root	localhost:59888	test	Sleep	46		NULL	0.000

+24	system user		NULL	Slave_IO	47	Waiting for master to send event	NULL	0.000

 connection backup_slave;

 BACKUP STAGE END;

 connection slave;

mysqltest: Result length mismatch

 - saving '/<<PKGBUILDDIR>>/builddir/mysql-test/var/5/log/rpl.parallel_backup_slave_binlog_off-innodb,mix/' to '/<<PKGBUILDDIR>>/builddir/mysql-test/var/log/rpl.parallel_backup_slave_binlog_off-innodb,mix/'

Retrying test rpl.parallel_backup_slave_binlog_off, attempt(2/3)...

worker[5] > Restart  - not started

worker[5] > Restart  - not started

***Warnings generated in error logs during shutdown after running tests: rpl.parallel_backup_slave_binlog_off

2023-10-09 11:20:36 26 [Warning] Slave: Lock wait timeout exceeded; try restarting transaction Error_code: 1205

2023-10-09 11:20:36 26 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2023-10-09 11:20:36 27 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2023-10-09 11:21:26 34 [Warning] Slave: Lock wait timeout exceeded; try restarting transaction Error_code: 1205

2023-10-09 11:21:26 34 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213

2023-10-09 11:21:27 35 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

It didn't repeat, seems like a sporadic issue.

Otto Kekäläinen added a comment - 2023-10-09 18:18 Seen in https://launchpadlibrarian.net/691101423/buildlog_ubuntu-mantic-riscv64.mariadb_1%3A10.11.5-3~bpo23.10.1~1696830433.49186426d72+feature.re.enable.all.tests_BUILDING.txt.gz for MariaDB 10.11.5 on riscv64 rpl.parallel_backup_slave_binlog_off 'innodb,mix' w5 [ fail ] Test ended at 2023-10-09 11:21:39 CURRENT_TEST: rpl.parallel_backup_slave_binlog_off --- /<<PKGBUILDDIR>>/mysql-test/suite/rpl/r/parallel_backup_slave_binlog_off.result 2023-08-11 06:35:40.000000000 +0000 +++ /<<PKGBUILDDIR>>/mysql-test/suite/rpl/r/parallel_backup_slave_binlog_off.reject 2023-10-09 11:21:35.273185963 +0000 @@ -145,6 +145,15 @@ BACKUP STAGE BLOCK_COMMIT; connection aux_slave; ROLLBACK; +Timeout in wait_condition.inc for SELECT COUNT(*) = 1 FROM information_schema.processlist WHERE state = "Waiting for backup lock" +Id User Host db Command Time State Info Progress +4 root localhost:59878 test Sleep 59 NULL 0.000 +5 root localhost:59880 test Sleep 61 NULL 0.000 +8 root localhost:59882 test Sleep 46 NULL 0.000 +9 root localhost:59884 test Sleep 59 NULL 0.000 +10 root localhost:59886 test Query 0 starting show full processlist 0.000 +15 root localhost:59888 test Sleep 46 NULL 0.000 +24 system user NULL Slave_IO 47 Waiting for master to send event NULL 0.000 connection backup_slave; BACKUP STAGE END; connection slave; mysqltest: Result length mismatch - saving '/<<PKGBUILDDIR>>/builddir/mysql-test/var/5/log/rpl.parallel_backup_slave_binlog_off-innodb,mix/' to '/<<PKGBUILDDIR>>/builddir/mysql-test/var/log/rpl.parallel_backup_slave_binlog_off-innodb,mix/' Retrying test rpl.parallel_backup_slave_binlog_off, attempt(2/3)... worker[5] > Restart - not started worker[5] > Restart - not started ***Warnings generated in error logs during shutdown after running tests: rpl.parallel_backup_slave_binlog_off 2023-10-09 11:20:36 26 [Warning] Slave: Lock wait timeout exceeded; try restarting transaction Error_code: 1205 2023-10-09 11:20:36 26 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2023-10-09 11:20:36 27 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 2023-10-09 11:21:26 34 [Warning] Slave: Lock wait timeout exceeded; try restarting transaction Error_code: 1205 2023-10-09 11:21:26 34 [Warning] Slave: Deadlock found when trying to get lock; try restarting transaction Error_code: 1213 2023-10-09 11:21:27 35 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964 It didn't repeat, seems like a sporadic issue.

Julien Fritsch made changes - 2023-11-28 10:24

Fix Version/s

10.9 [ 26905 ]

Julien Fritsch made changes - 2023-11-28 10:30

Fix Version/s

10.10 [ 27530 ]

Brandon Nesterenko added a comment - 2024-01-22 13:42 - edited

Also can occur with Slave connection was killed (seen on Ubuntu 20.04)

rpl.parallel_backup 'innodb,mix'         w4 [ fail ]  Found warnings/errors in server log file!

        Test ended at 2024-01-22 13:24:46

line

2024-01-22 13:24:45 35 [Warning] Slave: Connection was killed Error_code: 1927

2024-01-22 13:24:45 35 [Warning] Slave: Connection was killed Error_code: 1927

^ Found warnings in /home/buildbot/amd64-ubuntu-2004/build/mysql-test/var/4/log/mysqld.2.err

Brandon Nesterenko added a comment - 2024-01-22 13:42 - edited Also can occur with Slave connection was killed (seen on Ubuntu 20.04) rpl.parallel_backup 'innodb,mix' w4 [ fail ] Found warnings/errors in server log file! Test ended at 2024-01-22 13:24:46 line 2024-01-22 13:24:45 35 [Warning] Slave: Connection was killed Error_code: 1927 2024-01-22 13:24:45 35 [Warning] Slave: Connection was killed Error_code: 1927 ^ Found warnings in /home/buildbot/amd64-ubuntu-2004/build/mysql-test/var/4/log/mysqld.2.err

Daniel Black added a comment - 2024-03-06 05:02

Did lazy bump from 30 seconds to 60 in https://github.com/MariaDB/server/pull/3106

Daniel Black added a comment - 2024-03-06 05:02 Did lazy bump from 30 seconds to 60 in https://github.com/MariaDB/server/pull/3106

Daniel Black made changes - 2024-03-06 05:02

Assignee

Andrei Elkin [ elkin ]

Daniel Black [ danblack ]

Daniel Black made changes - 2024-03-06 05:02

Status

Open [ 1 ]

Confirmed [ 10101 ]

Daniel Black made changes - 2024-03-06 05:03

Assignee	Daniel Black [ danblack ]	Brandon Nesterenko [ JIRAUSER48702 ]
Status	Confirmed [ 10101 ]	In Review [ 10002 ]

Brandon Nesterenko added a comment - 2024-03-26 16:51 - edited

Thanks danblack! I left a suggestion on the PR.

Brandon Nesterenko added a comment - 2024-03-26 16:51 - edited Thanks danblack ! I left a suggestion on the PR.

Brandon Nesterenko made changes - 2024-03-26 16:51

Assignee	Brandon Nesterenko [ JIRAUSER48702 ]	Daniel Black [ danblack ]
Status	In Review [ 10002 ]	Stalled [ 10000 ]

Daniel Black added a comment - 2024-03-27 07:46

Thank you. Updated as suggested.

Lets see how it goes.

Daniel Black added a comment - 2024-03-27 07:46 Thank you. Updated as suggested. Lets see how it goes .

Daniel Black added a comment - 2024-04-01 23:38

Ok. Passing on builders it didn't previous. FreeBSD builder appears out to lunch.

Good to merge?

Daniel Black added a comment - 2024-04-01 23:38 Ok. Passing on builders it didn't previous. FreeBSD builder appears out to lunch. Good to merge?

Daniel Black made changes - 2024-04-01 23:38

Assignee	Daniel Black [ danblack ]	Brandon Nesterenko [ JIRAUSER48702 ]
Status	Stalled [ 10000 ]	In Review [ 10002 ]

Daniel Black made changes - 2024-04-15 04:36

issue.field.resolutiondate

2024-04-15 04:36:25.0

2024-04-15 04:36:24.523

Daniel Black made changes - 2024-04-15 04:36

Fix Version/s		10.5.25 [ 29626 ]
Fix Version/s	10.5 [ 23123 ]
Fix Version/s	10.6 [ 24028 ]
Fix Version/s	10.11 [ 27614 ]
Fix Version/s	11.0 [ 28320 ]
Fix Version/s	11.1 [ 28549 ]
Resolution		Fixed [ 1 ]
Status	In Review [ 10002 ]	Closed [ 6 ]

JiraAutomate made changes - 2024-04-15 04:36

Fix Version/s		10.6.18 [ 29627 ]
Fix Version/s		10.11.8 [ 29630 ]
Fix Version/s		11.0.6 [ 29628 ]
Fix Version/s		11.1.5 [ 29629 ]
Fix Version/s		11.2.4 [ 29631 ]

Sergei Golubchik made changes - 2024-04-15 08:30

Link

This issue is part of MDEV-33073 [ MDEV-33073 ]

MariaDB Server

rpl.parallel_backup* tests sometimes fail with Result length mismatch

Details

Description

Attachments

Issue Links

Activity

People

Dates

Git Integration