Bug #39382 innodb_max_dirty_pages_pct_func fails sporadically with timeout on pushbuild
Submitted: 11 Sep 2008 8:06 Modified: 4 Feb 2009 18:25
Reporter: Sven Sandberg Email Updates:
Status: Closed Impact on me:
None 
Category:Tests Severity:S7 (Test Cases)
Version:5.1, 6.0 OS:Any
Assigned to: Horst Hunger CPU Architecture:Any
Tags: innodb_max_dirty_pages_pct_func, pushbuild, sporadic, test failure, timeout

[11 Sep 2008 8:06] Sven Sandberg
Description:
pushbuild failure:

main.innodb_max_dirty_pages_pct_func     [ fail ]  timeout after 15 minutes

Test case timeout after 15 minute(s)

 == /dev/shm/var-ps_row-111/3/tmp/analyze-timeout-mysqld.1.err ==
mysqltest: Exit with failure! Call 'exit' in script to return with sucess
SHOW PROCESSLIST;
Id	User	Host	db	Command	Time	State	Info
81	root	localhost	test	Query	0	Sending data	CALL add_until(10)
84	root	localhost	NULL	Query	0	NULL	SHOW PROCESSLIST

 - saving '/dev/shm/var-ps_row-111/3/log/main.innodb_max_dirty_pages_pct_func/' to '/dev/shm/var-ps_row-111/log/main.innodb_max_dirty_pages_pct_func/'

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

How to repeat:
https://intranet.mysql.com/secure/pushbuild/showpush.pl?dir=bzr_mysql-5.1-rpl&order=53  'sapsrv2' -max/ps_row

This test has only failed with timeout once, according to xref: http://tinyurl.com/5pakbq
[12 Nov 2008 17:18] Andrei Elkin
I think the failure might relate to mtr not to the test.
There has been a few times today my mtr on both 5.1 and 6.0 displayed 

ndb.ndb_binlog_basic                     [ pass ]  14453
timer 16746: expired after 900 seconds
main.innodb_max_dirty_pages_pct_func     [ fail ]  timeout after 15 minutes

Test case timeout after 15 minute(s)

 == /dev/shm/var/3/tmp/analyze-timeout-mysqld.1.err ==
SHOW PROCESSLIST;
Id      User    Host    db      Command Time    State   Info
88      root    localhost       test    Query   0       NULL    INSERT INTO t1(b) VALUES('MYSQL')
91      root    localhost       NULL    Query   0       NULL    SHOW PROCESSLIST

 - saving '/dev/shm/var/3/log/main.innodb_max_dirty_pages_pct_func/' to '/dev/shm/var/log/main.innodb_max_dirty_pages_pct_func/'

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

main.innodb_max_dirty_pages_pct_func     [ pass ]    597

the elapsed timeout.

*However*, I was observing mtr execution and claim the test did not wait at all!

The test does not fail running alone.

Could it be that the mtr timer is not reset sometimes at the end of a test?
[10 Dec 2008 5:41] 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/61141

2742 He Zhenxing	2008-12-10 [merge]
      Auto Merge
[17 Dec 2008 14:15] Sven Sandberg
This has now failed 49 times.

It has also failed outside replication trees, so that proves it is not replication related: I'm removing the 5.1-rpl-green flag.

In 6.0-rpl, mtr has recently been improved so that it prints the last few lines from the query log when timeouts happen. This means we have better debug info for this bug now. See the xref above: it fails just after FLUSH STATUS.
[29 Jan 2009 18:36] 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/64551

2758 Horst Hunger	2009-01-29
      Fix for bug#39382: Replaced sleep by wait condition, inserted some comments and beautyfications.
[30 Jan 2009 9:30] 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/64593

2758 Horst Hunger	2009-01-30
       Revised fix for bug#39382 after 1. review: Replaced sleep by wait condition, inserted some comments and beautyfications.
[30 Jan 2009 13:10] 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/64625

2758 Horst Hunger	2009-01-30
      Revised fix for bug#39382 after 2. review.
[30 Jan 2009 13:30] 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:10] Bugs System
Pushed into 5.1.32 (revid:luis.soares@sun.com-20090129165946-d6jnnfqfokuzr09y) (version source revid:msvensson@mysql.com-20081205211552-7odzip74f430g1du) (merge vers: 5.1.31) (pib:6)
[30 Jan 2009 17:00] 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/64679

2761 Horst Hunger	2009-01-30
      Fix for bug#39382 including review results after pulling the bugteam tree now using the new mtr.
[30 Jan 2009 17:27] 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/64681

2990 Horst Hunger	2009-01-30
      Fix for bug#39382: merge from 5.1-bugteam to 6.0-bugteam.
[3 Feb 2009 9:40] Bugs System
Pushed into 5.1.32 (revid:joro@sun.com-20090203090549-gos3v4320vimrzg6) (version source revid:horst@mysql.com-20090130165910-dn5jpy131dp3200s) (merge vers: 5.1.32) (pib:6)
[4 Feb 2009 11:15] Bugs System
Pushed into 6.0.10-alpha (revid:kostja@sun.com-20090204104420-mw1i2u9lum4bxjo6) (version source revid:joro@sun.com-20090131161307-ydhtowoaf0m3nzu0) (merge vers: 6.0.10-alpha) (pib:6)
[4 Feb 2009 18:25] Paul DuBois
Test suite changes. No changelog entry needed.
[17 Feb 2009 14:55] 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:42] 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:19] 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-20090202111723-1zzwax187rtls913) (merge vers: 5.1.32-ndb-6.2.17) (pib:6)