Bug #47743 rpl.rpl_log_pos fails sporadically
Submitted: 30 Sep 2009 13:16 Modified: 15 Mar 2010 4:50
Reporter: Alexander Nozdrin Email Updates:
Status: Closed Impact on me:
None 
Category:MySQL Server: Replication Severity:S3 (Non-critical)
Version:5.4.5 (mysql-trunk) OS:Any
Assigned to: Luis Soares CPU Architecture:Any
Tags: pb2, sporadic, test failure

[30 Sep 2009 13:16] Alexander Nozdrin
Description:
rpl.rpl_log_pos [ fail ] Found warnings/errors in server log file!
        Test ended at 2009-09-29 11:01:52
file_name	line
G:/pb2/test/sb_1-775245-1254213197.9/mysql-5.4.5-beta-win-x86-test/mysql-test/var/log/mysqld.2.err	090929 12:01:51 [Warning] Slave I/O: Get master SERVER_ID failed with error: Lost connection to MySQL server during query, Error_code: 2013
^ Found warnings!!
ok
mysqltest: Logging to ''.
mysqltest: Results saved in ''.
mysqltest: Connecting to server localhost:10141 (socket G:/pb2/test/sb_1-775245-1254213197.9/mysql-5.4.5-beta-win-x86-test/mysql-test/var/tmp/mysqld.2.sock) as 'root', connection 'default', attempt 0 ...
mysqltest: ... Connected.
mysqltest: Start processing test commands from '.\include\check-warnings.test' ...
mysqltest: ... Done processing test commands.
mysqltest: Test has succeeded!

 - saving 'G:/pb2/test/sb_1-775245-1254213197.9/mysql-5.4.5-beta-win-x86-test/mysql-test/var/log/rpl.rpl_log_pos/' to 'G:/pb2/test/sb_1-775245-1254213197.9/mysql-5.4.5-beta-win-x86-test/mysql-test/var/log/rpl.rpl_log_pos/'

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

rpl.rpl_log_pos                          [ retry-pass ]    802

How to repeat:
http://pb2.norway.sun.com/web.py?template=mysql_show_test_failure&test_failure_id=2349129&...
[13 Oct 2009 0:04] 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/86613

3159 Luis Soares	2009-10-13
      BUG#47743: rpl.rpl_log_pos fails sporadically
      
      BUG 45214 fixed the case when get_master_version_and_clock
      function, used by the slave, would not report errors. Some of
      these errors can be related to transient network problems. This
      specific set of errors was fixed by having the slave to check the
      error code and, if network related, it now prints some warnings
      and tries to reconnect. If not network related, it just gives up
      and fails.
      
      However, sometimes the slave comes across some network issues
      while calling get_master_version_and_clock and consequently some
      warnings are printed out to the error log. This causes MTR to
      find and consider them harmful, ultimately causing the test case
      to fail.
      
      We fix this by deploying warning suppressions for these specific
      warnings.
[13 Oct 2009 8:59] Luis Soares
See also BUG#47983.
[4 Nov 2009 1:56] 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/89239

3159 Luis Soares	2009-11-04
      BUG#47743: rpl.rpl_log_pos fails sporadically
      BUG#47983: rpl_extraColmaster_myisam failed in PB2 with "Found
      warnings!!"
      
      BUG 45214 fixed the case when get_master_version_and_clock
      function, used by the slave, would not report errors. The slave
      now detects them and if related to transient network failures, it
      prints some warnings and retries to connect. On the other hand,
      if not network related, it just gives up and fails.
      
      As such, sometimes, in PB2, the slave comes across some transient
      communication issues between master and slave, while calling
      get_master_version_and_clock, causing warnings print outs to the
      error log. Nevertheless, in such cases slave retries to connect,
      in which it succeeds, and the test case continues as it normally
      would. But then, at the end of a successful test run, MTR checks
      the error log, finds the unexpected warnings and considers them
      harmful. This causes MTR to report error and, consequently, PB2
      to report a failing test.
      
      We fix this by adding to the global warnings suppress list the
      warnings related to transient network failures only, which are
      reported while in function get_master_version_and_clock.
[6 Nov 2009 17:17] Luis Soares
Queued in mysql-5.1-bugteam and mysql-pe.
[2 Dec 2009 8:07] Bugs System
Pushed into 5.1.42 (revid:joro@sun.com-20091202080033-mndu4sxwx19lz2zs) (version source revid:kristofer.pettersson@sun.com-20091109223504-xvwgsdqiyuve6frt) (merge vers: 5.1.41) (pib:13)
[3 Dec 2009 11:41] Jon Stephens
Test case only; closed without further action.
[16 Dec 2009 8:35] Bugs System
Pushed into 6.0.14-alpha (revid:alik@sun.com-20091216083311-xorsasf5kopjxshf) (version source revid:alik@sun.com-20091214191830-wznm8245ku8xo702) (merge vers: 6.0.14-alpha) (pib:14)
[16 Dec 2009 8:42] Bugs System
Pushed into 5.5.0-beta (revid:alik@sun.com-20091216082430-s0gtzibcgkv4pqul) (version source revid:alexey.kopytov@sun.com-20091124081906-6pqi7e7sajimog71) (merge vers: 5.5.0-beta) (pib:14)
[16 Dec 2009 8:49] Bugs System
Pushed into mysql-next-mr (revid:alik@sun.com-20091216083231-rp8ecpnvkkbhtb27) (version source revid:alik@sun.com-20091212203859-fx4rx5uab47wwuzd) (merge vers: 5.6.0-beta) (pib:14)
[16 Dec 2009 16:11] Jon Stephens
Closed. (See my previous comment.)
[12 Mar 2010 14:05] Bugs System
Pushed into 5.1.44-ndb-7.0.14 (revid:jonas@mysql.com-20100312135944-t0z8s1da2orvl66x) (version source revid:jonas@mysql.com-20100312115609-woou0te4a6s4ae9y) (merge vers: 5.1.44-ndb-7.0.14) (pib:16)
[12 Mar 2010 14:21] Bugs System
Pushed into 5.1.44-ndb-6.2.19 (revid:jonas@mysql.com-20100312134846-tuqhd9w3tv4xgl3d) (version source revid:jonas@mysql.com-20100312060623-mx6407w2vx76h3by) (merge vers: 5.1.44-ndb-6.2.19) (pib:16)
[12 Mar 2010 14:36] Bugs System
Pushed into 5.1.44-ndb-6.3.33 (revid:jonas@mysql.com-20100312135724-xcw8vw2lu3mijrhn) (version source revid:jonas@mysql.com-20100312103652-snkltsd197l7q2yg) (merge vers: 5.1.44-ndb-6.3.33) (pib:16)
[15 Mar 2010 4:50] Jon Stephens
Re-closed w/o further action; see previous comments.