Bug #39382 innodb_max_dirty_pages_pct_func fails sporadically with timeout on pushbuild
Submitted: 11 Sep 2008 10:06 Modified: 4 Feb 19:25
Reporter: Sven Sandberg
Status: Closed
Category:Tests Severity:S2 (Serious)
Version:5.1, 6.0 OS:Any
Assigned to: Horst Hunger Target Version:5.1+
Tags: pushbuild, innodb_max_dirty_pages_pct_func, timeout, sporadic, test failure
Triage: Triaged: D3 (Medium)

[11 Sep 2008 10: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 18: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 6: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 15: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 19: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 10: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 14: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 14: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 16: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 18: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 18: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 10: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 12: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 19:25] Paul DuBois
Test suite changes. No changelog entry needed.
[17 Feb 15: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 17: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 19: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)