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

RESOLVED WORKSFORME

Status

()

defect
--
major
RESOLVED WORKSFORME
11 years ago
6 years ago

People

(Reporter: sgautherie, Unassigned)

Tracking

({intermittent-failure})

Trunk
x86
All
Points:
---
Dependency tree / graph

Firefox Tracking Flags

(Not tracked)

Details

(Whiteboard: [test which aborts the suite] , )

Attachments

(2 attachments)

{
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.
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
(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
Duplicate of this bug: 499830
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/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/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/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/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
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=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/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=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)
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=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?
Blocks: 556622
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.
Posted patch WIP 1Splinter 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.