Closed Bug 484123 Opened 16 years ago Closed 11 years ago

Mochitest test_simpleManifest.html (and test_xhtmlManifest.xhtml) intermittently fails one test and then times out followed by failures in multiple tests

Categories

(Core :: Networking: Cache, defect)

Product:
Core
Component:
Networking: Cache
Platform:
x86
All
Type:
defect
Priority:
Not set
Severity:
major

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: sgautherie, Unassigned)

References

(
URL
)

Details

(Keywords: intermittent-failure, Whiteboard: [test which aborts the suite] )

Attachments

(2 files)

screenshot after first timeout
(deleted), image/png
Details
WIP 1
(deleted), patch
Details | Diff | Splinter Review 
{
http://tinderbox.mozilla.org/showlog.cgi?log=SeaMonkey/1237419769.1237425924.25841.gz
Linux comm-central dep unit test on 2009/03/18 16:42:49

*** 31003 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
*** 31004 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed out.
*** 31007 INFO Error: Unable to restore focus, expect failures and timeouts.
*** 31068 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_xhtmlManifest.xhtml | Test timed out.
*** 31071 INFO Error: Unable to restore focus, expect failures and timeouts.
[...]
*** 41401 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/geolocation/test_manyWindows.html | Error thrown during test: windows[i] is null - got 0, expected 1
}
Flags: wanted1.9.1?
(In reply to comment #0)
> *** 41401 ERROR TEST-UNEXPECTED-FAIL |
> /tests/dom/tests/mochitest/geolocation/test_manyWindows.html | Error thrown
> during test: windows[i] is null - got 0, expected 1

This part is bug 451949.
Summary: [SeaMonkey] Mochitest test_simpleManifest.html intermittently times out → [SeaMonkey] Mochitest test_simpleManifest.html (and test_xhtmlManifest.xhtml) intermittently times out
Whiteboard: [orange]
Bug 451949 comment 19 says bug 484122 caused this.
Depends on: 484122
Happened again:
http://tinderbox.mozilla.org/showlog.cgi?log=SeaMonkey/1237590448.1237596526.13756.gz
Linux comm-central dep unit test on 2009/03/20 16:07:28
Same log.
not sure if related but during this test run there have been crashes on m-c, for example:
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1238606166.1238611997.12746.gz
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1238599133.1238605868.953.gz

here is the stack from one of those logs:
http://mozilla.pastebin.com/f2c2b0813
s/this test/test_xhtmlManifest.xhtml/
Seen on 'OS X 10.5.2 mozilla-1.9.1 unit test' for the Firefox 3.5 tinderbox. Possibly related?

31393 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
</font>
command timed out: 300 seconds without output, killing pid 40260
process killed by signal 9
program finished with exit code -1

http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.5/1239292644.1239296283.16885.gz&fulltext=1
Happened again on non-SeaMonkey; this doesn't seem SeaMonkey-specific:

WINNT 5.2 mozilla-central unit test on 2009/04/20 03:57:59  
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1240225079.1240233528.27358.gz
Summary: [SeaMonkey] Mochitest test_simpleManifest.html (and test_xhtmlManifest.xhtml) intermittently times out → Mochitest test_simpleManifest.html (and test_xhtmlManifest.xhtml) intermittently fails one test and then times out
Linux mozilla-central unit test on 2009/05/11 09:17:44

http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1242058664.1242064640.3778.gz&fulltext=1
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1242220688.1242225493.16376.gz&fulltext=1 on OS X 10.5.2 mozilla-central unit test
WINNT 5.2 mozilla-central unit test on 2009/05/15 01:42:39  
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1242376959.1242383076.14993.gz
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1243023963.1243027781.3723.gz on Linux mozilla-central unit test
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.5/1243019271.1243027234.2721.gz
WINNT 5.2 mozilla-1.9.1 unit test on 2009/05/22 12:07:51
(In reply to comment #6)
> Seen on 'OS X 10.5.2 mozilla-1.9.1 unit test' for the Firefox 3.5 tinderbox.

Same on mozilla-central:

http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1243118371.1243122401.32501.gz
OS X 10.5.2 mozilla-central unit test on 2009/05/23 15:39:31
OS: Linux → All
Version: 1.9.1 Branch → Trunk
http://tinderbox.mozilla.org/showlog.cgi?log=SeaMonkey-Ports/1245295298.1245303128.23690.gz
WINNT 5.2 comm-1.9.1 unit test on 2009/06/17 20:21:38
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1248094926.1248103382.24421.gz
WINNT 5.2 mozilla-central unit test on 2009/07/20 06:02:06
jduell, can you take this please? Rando-oranges are killing us on m-c.
Assignee: nobody → jduell.mcbugs
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1248263599.1248267481.28833.gz
OS X 10.5.2 mozilla-central unit test on 2009/07/22 04:53:19  

32577 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)

command timed out: 300 seconds without output, killing pid 98730
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1248338399.1248342862.26563.gz
OS X 10.5.2 mozilla-central unit test on 2009/07/23 01:39:59
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1248469821.1248478122.1578.gz
OS X 10.5.2 mozilla-central unit test on 2009/07/24 14:10:21
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1248713946.1248721113.9801.gz
Linux mozilla-central unit test on 2009/07/27 09:59:06
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1248822541.1248827579.14140.gz
OS X 10.5.2 mozilla-central unit test on 2009/07/28 16:09:01
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1249339865.1249342410.30340.gz
Linux mozilla-central test mochitests on 2009/08/03 started 15:51, finished 16:34
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1249566610.1249569988.11899.gz
WINNT 5.2 mozilla-central test mochitests on 2009/08/06 Started 09:50, finished 10:47

This seemed to coincide with other boxes loosing their connection
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1249602840.1249610149.26343.gz
OS X 10.5.2 mozilla-central unit test

Please, fix or disable tests which abort the suite.
Flags: wanted1.9.2?
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1249628248.1249635534.16543.gz
OS X 10.5.2 mozilla-central unit test
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1250023313.1250027619.16794.gz
OS X 10.5.2 mozilla-central unit test on 2009/08/11 13:41:53
http://tinderbox.mozilla.org/showlog.cgi?log=SeaMonkey2.0/1250145510.1250150044.23371.gz
OS X 10.4 comm-central unit test on 2009/08/12 23:38:30


(In reply to comment #27)
> Please, fix or disable tests which abort the suite.

Please!
status1.9.1: --- → ?
Flags: wanted1.9.1?
Whiteboard: [orange] → [test which aborts the suite] [orange]
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1250150079.1250153948.12081.gz
OS X 10.5.2 mozilla-central unit test on 2009/08/13 00:54:39  
33069 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
buildbot.slave.commands.TimeoutError: command timed out: 300 seconds without output, killing pid 54921
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1250151001.1250157094.15598.gz
Linux mozilla-central unit test on 2009/08/13 01:10:01  
33061 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
buildbot.slave.commands.TimeoutError: command timed out: 300 seconds without output, killing pid 3831
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1250617979.1250621193.8478.gz
WINNT 5.2 mozilla-central test mochitests on 2009/08/18 10:52:59
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1250825652.1250832374.23187.gz
OS X 10.5.2 mozilla-central unit test on 2009/08/20 20:34:12
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1250854627.1250861324.23800.gz
OS X 10.5.2 mozilla-central unit test on 2009/08/21 04:37:07
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1250873904.1250876773.22635.gz
WINNT 5.2 mozilla-central test mochitests on 2009/08/21 09:58:24
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1251043153.1251046316.21426.gz
OS X 10.5.2 mozilla-central test mochitests on 2009/08/23 08:59:13

33653 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
33654 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed out.
33657 INFO Error: Unable to restore focus, expect failures and timeouts.
33720 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_xhtmlManifest.xhtml | Test timed out.
33723 INFO Error: Unable to restore focus, expect failures and timeouts.
[...]
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1251079604.1251084852.18530.gz
OS X 10.5.2 mozilla-central unit test on 2009/08/23 19:06:44
{
33641 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)

command timed out: 300 seconds without output, killing pid 72255
}

P-L-E-A-S-E !
Severity: normal → major
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.5/1251384388.1251387831.21281.gz
WINNT 5.2 mozilla-1.9.1 unit test on 2009/08/27 07:46:28
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1251759425.1251766724.14301.gz
Linux mozilla-central unit test on 2009/08/31 15:57:05
WINNT 5.2 mozilla-central unit test
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1252063177.1252068289.945.gz
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1252103322.1252107459.28742.gz
OS X 10.5.2 mozilla-central unit test on 2009/09/04 15:28:42
CCing Dave Camp, author of the test, in case he has any insight into the failure.
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.6/1252353142.1252363388.11346.gz
OS X 10.5.2 mozilla-1.9.2 unit test on 2009/09/07 12:52:22
Linux mozilla-central test mochitests  [testfailed] Started 15:51, finished 16:50
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1252623118.1252626565.15880.gz&fulltext=1
Linux mozilla-1.9.2 test mochitests [testfailed] Started 18:16, finished 19:15
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.6-Unittest/1252849576.1252853056.24203.gz
http://tinderbox.mozilla.org/showlog.cgi?log=SeaMonkey2.0/1254253294.1254259399.22547.gz
WINNT 5.2 comm-1.9.1 unit test on 2009/09/29 12:41:34
Linux mozilla-1.9.2 test mochitests
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.6-Unittest/1254861853.1254865152.27554.gz
http://tinderbox.mozilla.org/showlog.cgi?log=SeaMonkey2.0/1254959905.1254964057.14704.gz
Linux comm-1.9.1 unit test on 2009/10/07 16:58:25
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.6-Unittest/1255717091.1255719826.954.gz

WINNT 5.2 mozilla-1.9.2 test mochitests  [testfailed] Started 11:18, finished 12:04
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1256311266.1256312587.29108.gz
Linux mozilla-central test mochitests-2/5 on 2009/10/23 08:21:06
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1256945052.1256946515.13268.gz
OS X 10.5.2 mozilla-central test mochitests-2/5 on 2009/10/30 16:24:12
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1257162042.1257164899.14062.gz
WINNT 5.2 mozilla-central test debug mochitests-2/5 on 2009/11/02 03:40:42
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1257293307.1257296167.24294.gz
Linux mozilla-central test debug mochitests-2/5 on 2009/11/03 16:08:27
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1257291238.1257293419.25839.gz
WINNT 5.2 mozilla-central test opt mochitests-2/5 on 2009/11/03 15:33:58
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1257507373.1257509674.25652.gz
OS X 10.5.2 mozilla-central debug test mochitests-2/5 on 2009/11/06 03:36:13
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1257526704.1257530403.8898.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2009/11/06 08:58:24
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1257535520.1257537048.19483.gz
WINNT 5.2 mozilla-central test mochitests-2/5 on 2009/11/06 11:25:20
FWIW, recently (previous 5 comments at least) have the following in common:
 - No longer aborts the suite
 - This bug's test-failure is followed by a failure in "test_Prototype.html":
     testFormActivating - 3 assertions, 1 failures, 0 errors"
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.5-Unittest/1257549784.1257553201.6623.gz
WINNT 5.2 mozilla-1.9.1 test mochitests on 2009/11/06 15:23:04
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1257863219.1257867237.23151.gz
Linux mozilla-central debug test mochitests-2/5 on 2009/11/10 06:26:59
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1258143631.1258146495.28311.gz
Linux mozilla-central debug test mochitests-2/5 on 2009/11/13 12:20:31
s: moz2-linux-slave42
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1258466381.1258469195.9432.gz
Linux mozilla-central debug test mochitests-2/5 on 2009/11/17 05:59:41  
s: moz2-linux-slave12
2788 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
2789 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed out.
2855 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_xhtmlManifest.xhtml | Test timed out.
(whole suite did not time out)
OS X 10.5.2 mozilla-1.9.2 test mochitests [testfailed] 

http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.6-Unittest/1258493112.1258498611.21632.gz
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1258601860.1258603464.28700.gz
WINNT 5.2 mozilla-central opt test mochitests-2/5 on 2009/11/18 19:37:40
"s: moz2-win32-slave19"
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1259027261.1259030121.28886.gz
Linux mozilla-central debug test mochitests-2/5 on 2009/11/23 17:47:41
s: moz2-linux-slave09
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1259022829.1259025561.10320.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2009/11/23 16:33:49
"s: moz2-win32-slave10"
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.5-Unittest/1259099805.1259103152.25532.gz
WINNT 5.2 mozilla-1.9.1 test mochitests
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox-Unittest/1260729049.1260731680.15391.gz
Linux mozilla-central debug test mochitests-2/5 on 2009/12/13 10:30:49
s: moz2-linux-slave42
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1261022410.1261025440.28800.gz
Linux mozilla-central debug test mochitests-2/5 on 2009/12/16 20:00:10
s: moz2-linux-slave01
2764 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
2765 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed out.
2831 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_xhtmlManifest.xhtml | Test timed out.
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1261119440.1261121529.5189.gz
Linux mozilla-central debug test mochitests-2/5 on 2009/12/17 22:57:20
s: moz2-linux-slave16
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1261174459.1261177434.23357.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2009/12/18 14:14:19
s: win32-slave05
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1261174459.1261177434.23357.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2009/12/18 14:14:19
s: win32-slave05
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1261402923.1261405549.20491.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2009/12/21 05:42:03
s: win32-slave24
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1264582166.1264584404.28775.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2010/01/27 00:49:26
s: win32-slave15
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1264621165.1264624358.18621.gz
OS X 10.5.2 mozilla-central debug test mochitests-2/5 on 2010/01/27 11:39:25
s: moz2-darwin9-slave14
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1265413194.1265416591.12226.gz
Linux mozilla-central debug test mochitests-2/5 on 2010/02/05 15:39:54
s: moz2-linux-slave05
2763 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
2764 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed out.
2830 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_xhtmlManifest.xhtml | Test timed out.
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1265686788.1265689744.2309.gz
OS X 10.5.2 mozilla-central debug test mochitests-2/5 on 2010/02/08 19:39:48
s: moz2-darwin9-slave12
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1265746587.1265749720.1473.gz
OS X 10.5.2 mozilla-central debug test mochitests-2/5 on 2010/02/09 12:16:27
s: moz2-darwin9-slave02
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1265900798.1265903847.17325.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2010/02/11 07:06:38
s: win32-slave12
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1267081879.1267085554.20302.gz
OS X 10.5.2 mozilla-central debug test mochitests-2/5 on 2010/02/24 23:11:19
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1269568206.1269570805.3580.gz
Linux mozilla-central debug test mochitests-2/5 on 2010/03/25 18:50:06
s: moz2-linux-slave25
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1269886385.1269888993.9488.gz
Linux mozilla-central debug test mochitests-2/5 on 2010/03/29 11:13:05
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1270150846.1270154317.7704.gz
WINNT 5.2 mozilla-central debug test mochitests-2/5 on 2010/04/01 12:40:46
s: win32-slave14
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1270505895.1270508408.17249.gz
Linux mozilla-central debug test mochitests-2/5 on 2010/04/05 15:18:15
s: moz2-linux-slave19
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1270768384.1270770471.6514.gz
Linux mozilla-central debug test mochitests-2/5 on 2010/04/08 16:13:04
s: moz2-linux-slave26
Please see bug #549767, comment #65 and beyond. I have a suggested patch to "test_simpleManifest.html" - should I attach it here?
OS X 10.5.2 mozilla-1.9.2 opt test mochitests on 2010/06/24 22:31:42
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox3.6/1277443902.1277446578.17353.gz
Blocks: 556622
Attached image screenshot after first timeout (deleted) — 
http://tinderbox.mozilla.org/showlog.cgi?tree=MozillaTry&errorparser=unittest&logfile=1281000722.1281003252.28926.gz&buildtime=1281000722&buildname=Rev3%20Fedora%2012x64%20tryserver%20debug%20test%20mochitests-2%2f5&fulltext=1#err4
Rev3 Fedora 12x64 tryserver debug test mochitests-2/5 on 2010/08/05 02:32:02

5014 ERROR TEST-UNEXPECTED-FAIL |
/tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html |
applicationCache should have one dynamic entry (deprecated API)
5015 ERROR TEST-UNEXPECTED-FAIL |
/tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed
out.
5081 ERROR TEST-UNEXPECTED-FAIL |
/tests/dom/tests/mochitest/ajax/offline/test_xhtmlManifest.xhtml | Test timed
out.
5300 ERROR TEST-UNEXPECTED-FAIL |
/tests/dom/tests/mochitest/ajax/prototype/test_Prototype.html |
testFormActivating - 3 assertions, 1 failures, 0 errors
6347 ERROR TEST-UNEXPECTED-FAIL |
/tests/dom/tests/mochitest/bugs/test_bug265203.html | Test timed out.
philringnalda%gmail.com
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1281936537.1281939086.10478.gz
Rev3 Fedora 12 mozilla-central debug test mochitests-2/5 on 2010/08/15 22:28:57

s: talos-r3-fed-019
5123 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
5124 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed out.
5190 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_xhtmlManifest.xhtml | Test timed out.
5409 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/prototype/test_Prototype.html | testFormActivating - 3 assertions, 1 failures, 0 errors
6456 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/bugs/test_bug265203.html | Test timed out.
For clarity, here's the output of the test with DOMWINDOW lines removed:

5621 INFO TEST-START | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html
before 708608, after 708608, break 109a03000
5622 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache.mozItems should be available and empty before associating with a cache.
WARNING: NS_ENSURE_TRUE(sf) failed: file /builds/slave/try-osx64-dbg/build/docshell/base/nsDocShell.cpp, line 4992
WARNING: NS_ENSURE_TRUE(sf) failed: file /builds/slave/try-osx64-dbg/build/docshell/base/nsDocShell.cpp, line 4992
WARNING: 1 sort operation has occurred for the SQL statement '0x1257bea28'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
WARNING: 1 sort operation has occurred for the SQL statement '0x127194b88'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
5623 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should be an event target
5624 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | CHECKING state during initial update - 0 should equal 0
WARNING: 1 sort operation has occurred for the SQL statement '0x1257bea28'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
WARNING: 1 sort operation has occurred for the SQL statement '0x127194b88'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
5625 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | DOWNLOADING state during initial update - 0 should equal 0
WARNING: 1 sort operation has occurred for the SQL statement '0x127194b88'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
WARNING: 1 sort operation has occurred for the SQL statement '0x127194b88'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
WARNING: 1 sort operation has occurred for the SQL statement '0x127194b88'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
WARNING: 1 sort operation has occurred for the SQL statement '0x1257bea28'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
WARNING: 1 sort operation has occurred for the SQL statement '0x127194b88'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
5626 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Should get a checking event
5627 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Should get a downloading event
5628 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | http://mochi.test:8888/tests/dom/tests/mochitest/ajax/offline/simpleManifest.cacheManifest should exist in the offline cache
before 708608, after 708608, break 109a03000
5629 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | http://mochi.test:8888/tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html should exist in the offline cache
5630 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | http://mochi.test:8888/tests/SimpleTest/SimpleTest.js should exist in the offline cache
5631 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | http://mochi.test:8888/MochiKit/packed.js should exist in the offline cache
5632 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | http://mochi.test:8888/tests/dom/tests/mochitest/ajax/offline/offlineTests.js should exist in the offline cache
5633 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | https://mochi.test:8888/MochiKit/packed.js should not exist in the offline cache
5634 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | bad:/uri/invalid should not exist in the offline cache
5635 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Cache status should be 1 (CACHED) - 1 should equal 1
5636 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | application.swapCache() should fail after initial update.
WARNING: 1 sort operation has occurred for the SQL statement '0x1257bea28'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
WARNING: 1 sort operation has occurred for the SQL statement '0x127194b88'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file /builds/slave/try-osx64-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 139
before 708608, after 708608, break 109a03000
5637 INFO TEST-PASS | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | http://mochi.test:8888/tests/SimpleTest/EventUtils.js should exist in the offline cache
NEXT ERROR 5638 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | applicationCache should have one dynamic entry (deprecated API)
JavaScript error: , line 0: uncaught exception: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIDOMOfflineResourceList.mozItem]"  nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)"  location: "JS frame :: http://mochi.test:8888/tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html :: addFinished :: line 25"  data: no]
NEXT ERROR 5639 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html | Test timed out

So we get as far as expecting that the newly added entry is actually in the cache (but http://mxr.mozilla.org/mozilla-central/source/dom/tests/mochitest/ajax/offline/offlineTests.js#312 makes me think that it may not be good enough).  Checking mozLength fails (presumably we have zero items), and the following call to mozItem(0) fails (presumably because we fail the check at http://mxr.mozilla.org/mozilla-central/source/dom/src/offline/nsDOMOfflineResourceList.cpp#352).  I'm thinking we probably just need to wait longer to ensure that the item has actually been cached properly.
Attached patch WIP 1 (deleted) — — Splinter Review 
The comment at waitForAdd is mostly lies.  We can wait for the cache notification like this patch does.

This patch mostly works OK, except that the dom/tests/mochitest/ajax/offline/test_updatingManifest.html test fails.  I didn't have time to investigate why, but Jason may be able to look into it...
Attachment #522163 - Flags: feedback?(jduell.mcbugs)
Comment on attachment 522163 [details] [diff] [review]
WIP 1

Honza:  you know the app cache--can you take a look at this (see comment 243 and this patch in particular)
Attachment #522163 - Flags: feedback?(jduell.mcbugs) → feedback?(honzab.moz)
Assignee: jduell.mcbugs → honzab.moz
Honza, any chance you can take a look at this, please?
Do we know the exact cause of the intermittent failure and why the test times out?  Is there some analyzes why it happens and why the current wait mechanism is not sufficient?  Maybe it is somewhere in this bug, but I cannot find it among the orange-star comments..
(In reply to comment #243)
> This patch mostly works OK, except that the
> dom/tests/mochitest/ajax/offline/test_updatingManifest.html test fails.  I
> didn't have time to investigate why, but Jason may be able to look into it...

I can look at this.
(In reply to comment #250)
> Maybe it is somewhere in this bug, but I cannot find it
> among the orange-star comments..

Install Bugzilla Tweaks and click the "Collapse all tbplrobot comments" link.
Comment on attachment 522163 [details] [diff] [review]
WIP 1

So, the idea is not bad, but:

- there can be more updates in parallel (the case why test_updatingManifest.html might fail) so I would add a second argument to waitForAdd with URL of the expected manifest the update we want to observe
- also there is an API to watch for new updates coming ("offline-cache-update-added" observer), so we might setup waitForAdd before the actual action is taken to be sure we capture the correct update
- one major thing: where did the cache entry presence check go? :)
Attachment #522163 - Flags: feedback?(honzab.moz)
It actually looks like the specific problem Eshan ran in to (in dom/tests/mochitest/ajax/offline/test_updatingManifest.html) is a test for implicitly-cached resources, which we don't even do anymore.  So I'm not sure why that test was passing at all (grumble), but it should be ok to stop testing implicit caching.
(In reply to comment #254)
> It actually looks like the specific problem Eshan ran in to (in
> dom/tests/mochitest/ajax/offline/test_updatingManifest.html) is a test for
> implicitly-cached resources, which we don't even do anymore.

Since when?  I don't know about any such change.
Opportunistic caching support was removed in bug 460327.
You talk about implicitly-cached resources in your comment.  That confused me. The manifest used for the test doesn't use any opportunistic namespace entries.  opp.html are just left overs and are used for fallback namespace logic test.
Looks like someone's bribe to IT to not serve the log for this failure to tbpl is working, http://tinderbox.mozilla.org/showlog.cgi?log=Fx-Team/1309296550.1309299050.4400.gz is actually the second one in the last few days that I was completely unable to persuade tbpl to load.
http://tinderbox.mozilla.org/showlog.cgi?log=Mozilla-Inbound/1309450978.1309453308.13536.gz

Actually, not a bribe, just the newly-included after the first failure

TEST-UNEXPECTED-FAIL |  | [SimpleTest/SimpleTest.js, window.onerror] - An error occurred: uncaught exception: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIDOMOfflineResourceList.mozItem]"  nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)"  location: "JS frame :: http://mochi.test:8888/tests/dom/tests/mochitest/ajax/offline/test_simpleManifest.html :: addFinished :: line 25"  data: no] at :0

which could well be A Clue.
Summary: Mochitest test_simpleManifest.html (and test_xhtmlManifest.xhtml) intermittently fails one test and then times out → Mochitest test_simpleManifest.html (and test_xhtmlManifest.xhtml) intermittently fails one test and then times out followed by failures in multiple tests
https://tbpl.mozilla.org/php/getParsedLog.php?id=6340522
status1.9.1: ? → ---
Flags: wanted1.9.2?
Releasing for now, any one feel free to take this bug.  I can get to this later, though.
Assignee: honzab.moz → nobody
Whiteboard: [test which aborts the suite] [orange] → [test which aborts the suite]
Resolving WFM keyword:intermittent-failure bugs last modified >3 months ago, whose whiteboard contains none of:
{random,disabled,marked,fuzzy,todo,fails,failing,annotated,time-bomb,leave open}

There will inevitably be some false positives; for that (and the bugspam) I apologise. Filter on orangewfm.
Status: NEW → RESOLVED
Closed: 11 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: