Bug #41708 rpl.rpl_flushlog_loop fails in pushbuild producing non-deterministic result file
Submitted: 23 Dec 2008 10:27 Modified: 30 Jan 2009 18:08
Reporter: Alfranio Tavares Correia Junior Email Updates:
Status: Closed Impact on me:
None 
Category:MySQL Server: Tests Severity:S3 (Non-critical)
Version:5.1-rpl,6.0-rpl OS:Any
Assigned to: Zhenxing He CPU Architecture:Any
Tags: 6.0-rpl-green, pushbuild, test failure

[23 Dec 2008 10:27] Alfranio Tavares Correia Junior
Description:
The test always fails even after creating a new result file by executing 

./mtr --record rpl_flushlog_loop

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

rpl.rpl_flushlog_loop                    [ fail ]
        Test ended at 2008-12-18 22:25:03

CURRENT_TEST: rpl.rpl_flushlog_loop
--- /data0/pushbuild/pb/bzr_mysql-6.0-rpl/128/bzr_mysql-6.0-rpl-exp/mysql-test/suite/rpl/r/rpl_flushlog_loop.result	2008-12-18 21:56:09.000000000 +0300
+++ /data0/pushbuild/pb/bzr_mysql-6.0-rpl/128/bzr_mysql-6.0-rpl-exp/mysql-test/suite/rpl/r/rpl_flushlog_loop.reject	2008-12-19 00:25:03.000000000 +0300
@@ -16,49 +16,30 @@
 master_password='',master_port=MASTER_PORT;
 start slave;
 stop slave;
-change master to master_host='127.0.0.1',master_user='root',
-master_password='',master_port=SLAVE_PORT;
-include/start_slave.inc
-FLUSH LOGS;
+**** ERROR: failed while waiting for slave parameter Slave_IO_Running = No ****
+Message: Failed while waiting for slave to stop
+Note: the following output may have changed since the failure was detected
+**** Showing SLAVE STATUS, PROCESSLIST, and BINLOG EVENTS on slave ****
 SHOW SLAVE STATUS;
-Slave_IO_State	#
-Master_Host	127.0.0.1
-Master_User	root
-Master_Port	SLAVE_PORT
-Connect_Retry	60
-Master_Log_File	slave-bin.000001
-Read_Master_Log_Pos	POSITION
-Relay_Log_File	#
-Relay_Log_Pos	#
-Relay_Master_Log_File	slave-bin.000001
-Slave_IO_Running	Yes
-Slave_SQL_Running	Yes
-Replicate_Do_DB	
-Replicate_Ignore_DB	
-Replicate_Do_Table	
-Replicate_Ignore_Table	#
-Replicate_Wild_Do_Table	
-Replicate_Wild_Ignore_Table	
-Last_Errno	0
-Last_Error	
-Skip_Counter	0
-Exec_Master_Log_Pos	POSITION
-Relay_Log_Space	#
-Until_Condition	None
-Until_Log_File	
-Until_Log_Pos	0
-Master_SSL_Allowed	No
-Master_SSL_CA_File	
-Master_SSL_CA_Path	
-Master_SSL_Cert	
-Master_SSL_Cipher	
-Master_SSL_Key	
-Seconds_Behind_Master	#
-Master_SSL_Verify_Server_Cert	#
-Last_IO_Errno	#
-Last_IO_Error	
-Last_SQL_Errno	0
-Last_SQL_Error	
-Replicate_Ignore_Server_Ids	
-Master_Server_Id	2
-DROP TABLE t1;
+SHOW PROCESSLIST;
+Id	User	Host	db	Command	Time	State	Info
+2	root	localhost	test	Sleep	304	NULL	NULL
+3	root	localhost:54571	test	Query	0	NULL	SHOW PROCESSLIST
+4	root	localhost:54572	test	Sleep	304	NULL	NULL
+6	root	localhost:54576	NULL	Binlog Dump	304	Master has sent all binlog to slave; waiting for binlog to be updated	NULL
+SHOW BINLOG EVENTS IN 'master-bin.000001';
+Log_name	Pos	Event_type	Server_id	End_log_pos	Info
+master-bin.000001	4	Format_desc	1	107	Server ver: 6.0.9-alpha-pb128-debug-log, Binlog ver: 4
+**** Showing MASTER STATUS, PROCESSLIST, and BINLOG EVENTS on master ****
+[on master]
+SHOW MASTER STATUS;
+File	master-bin.000001
+Position	107
+Binlog_Do_DB	
+Binlog_Ignore_DB	
+SHOW PROCESSLIST;
+Id	User	Host	db	Command	Time	State	Info
+2	root	localhost	test	Sleep	304	NULL	NULL
+3	root	localhost:54571	test	Query	0	NULL	SHOW PROCESSLIST
+4	root	localhost:54572	test	Sleep	304	NULL	NULL
+6	root	localhost:54576	NULL	Binlog Dump	304	Master has sent all binlog to slave; waiting for binlog to be updated	NULL

mysqltest: In included file "./include/wait_for_slave_param.inc": Result content mismatch

 - saving '/dev/shm/var-n_mix-120/log/rpl.rpl_flushlog_loop/' to '/dev/shm/var-n_mix-120/log/rpl.rpl_flushlog_loop/'

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

How to repeat:
https://intranet.mysql.com/secure/pushbuild/showpush.pl?dir=bzr_mysql-6.0-rpl&order=128

https://intranet.mysql.com/secure/pushbuild/xref.pl?startdate=&enddate=&dir=&plat=&testtyp...
[26 Dec 2008 8:20] Bugs System
A patch for this bug has been committed. After review, it may
be pushed to the relevant source trees for release in the next
version. You can access the patch from:

  http://lists.mysql.com/commits/62338

2708 He Zhenxing	2008-12-26
      BUG#41708 rpl.rpl_flushlog_loop fails in pushbuild producing non-deterministic result file
      
      If server has not been initialized as a slave (by CHANGE MASTER), then
      SHOW SLAVE STATUS will return an empty set, and caused the waiting for
      Slave_IO_running or Slave_SQL_running to 'No' fail.
      
      This patch fixed the problem by return immediately if slave is not
      initialized in include/wait_for_slave_*_to_stop.inc.
[29 Dec 2008 9:59] Bugs System
A patch for this bug has been committed. After review, it may
be pushed to the relevant source trees for release in the next
version. You can access the patch from:

  http://lists.mysql.com/commits/62398

2772 He Zhenxing	2008-12-29 [merge]
      Merge patch of BUG#41708 from 5.1-rpl
[7 Jan 2009 13:40] Bugs System
A patch for this bug has been committed. After review, it may
be pushed to the relevant source trees for release in the next
version. You can access the patch from:

  http://lists.mysql.com/commits/62595

2774 He Zhenxing	2009-01-07 [merge]
      Auto merge
[7 Jan 2009 14:26] Bugs System
A patch for this bug has been committed. After review, it may
be pushed to the relevant source trees for release in the next
version. You can access the patch from:

  http://lists.mysql.com/commits/62605

2709 He Zhenxing	2009-01-07 [merge]
      Auto merge
[8 Jan 2009 8:34] Bugs System
A patch for this bug has been committed. After review, it may
be pushed to the relevant source trees for release in the next
version. You can access the patch from:

  http://lists.mysql.com/commits/62656

2777 He Zhenxing	2009-01-08
      enable rpl_flushlog_loop test after fix BUG#41708
[9 Jan 2009 8:34] Zhenxing He
pushed to 5.1-rpl and 6.0-rpl
[30 Jan 2009 13:27] Bugs System
Pushed into 6.0.10-alpha (revid:luis.soares@sun.com-20090129165607-wiskabxm948yx463) (version source revid:luis.soares@sun.com-20090129163120-e2ntks4wgpqde6zt) (merge vers: 6.0.10-alpha) (pib:6)
[30 Jan 2009 15:07] Bugs System
Pushed into 5.1.32 (revid:luis.soares@sun.com-20090129165946-d6jnnfqfokuzr09y) (version source revid:zhenxing.he@sun.com-20090107142538-aeoxq3lddk0g4uff) (merge vers: 5.1.31) (pib:6)
[30 Jan 2009 18:08] Paul DuBois
Test case changes. No changelog entry needed.
[17 Feb 2009 14:53] Bugs System
Pushed into 5.1.32-ndb-6.3.23 (revid:tomas.ulin@sun.com-20090217131017-6u8qz1edkjfiobef) (version source revid:tomas.ulin@sun.com-20090203133556-9rclp06ol19bmzs4) (merge vers: 5.1.32-ndb-6.3.22) (pib:6)
[17 Feb 2009 16:40] Bugs System
Pushed into 5.1.32-ndb-6.4.3 (revid:tomas.ulin@sun.com-20090217134419-5ha6xg4dpedrbmau) (version source revid:tomas.ulin@sun.com-20090203133556-9rclp06ol19bmzs4) (merge vers: 5.1.32-ndb-6.3.22) (pib:6)
[17 Feb 2009 18:17] Bugs System
Pushed into 5.1.32-ndb-6.2.17 (revid:tomas.ulin@sun.com-20090217134216-5699eq74ws4oxa0j) (version source revid:tomas.ulin@sun.com-20090201210519-vehobc4sy3g9s38e) (merge vers: 5.1.32-ndb-6.2.17) (pib:6)