847099 - Intermittent test_readonly_lockedfiles.html | Assertion count {2,4} is greater than expected range (Didn't find the locked file we were looking for!: 'mLockedFileQueues.Length()...) followed by a timeout in test_add_put.html et al (bug 783913)

Reporter

Description

•

12 years ago

Yay, another intermittent failure in the IndexedDB test suite. I don't suppose it's asking too much for these to be fixed one of these days? https://tbpl.mozilla.org/php/getParsedLog.php?id=20260072&tree=Mozilla-Inbound Rev4 MacOSX Lion 10.7 mozilla-inbound debug test mochitest-2 on 2013-03-02 11:20:25 PST for push 18c3ffc28f4a slave: talos-r4-lion-026 11:30:46 INFO - 3865 INFO TEST-START | /tests/dom/file/test/test_readonly_lockedfiles.html 11:30:46 INFO - ++DOMWINDOW == 63 (0x153a9b3b8) [serial = 2023] [outer = 0x11d205858] 11:30:46 INFO - 3866 INFO TEST-PASS | /tests/dom/file/test/test_readonly_lockedfiles.html | Correct mode 11:30:46 INFO - 3867 INFO TEST-PASS | /tests/dom/file/test/test_readonly_lockedfiles.html | Writing to a readonly locked file failed 11:30:46 INFO - 3868 INFO TEST-PASS | /tests/dom/file/test/test_readonly_lockedfiles.html | Appending to a readonly locked file failed 11:30:46 INFO - 3869 INFO TEST-PASS | /tests/dom/file/test/test_readonly_lockedfiles.html | Truncating a readonly locked file failed 11:30:46 INFO - 3870 INFO TEST-PASS | /tests/dom/file/test/test_readonly_lockedfiles.html | Flushing a readonly locked file failed 11:30:46 INFO - [Parent 464] ###!!! ASSERTION: Didn't find the locked file we were looking for!: 'mLockedFileQueues.Length() == lockedFileCount - 1', file ../../../dom/file/FileService.cpp, line 467 11:30:46 INFO - mozilla::dom::file::FileService::NotifyLockedFileCompleted(mozilla::dom::file::LockedFile*) [obj-firefox/dist/include/nsTArray.h:277] 11:30:46 INFO - mozilla::dom::file::FinishHelper::Run() [obj-firefox/dist/include/nsCOMPtr.h:520] 11:30:46 INFO - nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:627] 11:30:46 INFO - NS_ProcessPendingEvents_P(nsIThread*, unsigned int) [obj-firefox/xpcom/build/nsThreadUtils.cpp:188] 11:30:46 INFO - nsBaseAppShell::NativeEventCallback() [widget/xpwidgets/nsBaseAppShell.cpp:98] 11:30:46 INFO - nsAppShell::ProcessGeckoEvents(void*) [widget/cocoa/nsAppShell.mm:388] 11:30:46 INFO - CoreFoundation + 0x12b51 11:30:46 INFO - CoreFoundation + 0x123bd 11:30:46 INFO - CoreFoundation + 0x391a9 11:30:46 INFO - CoreFoundation + 0x38ae6 11:30:46 INFO - HIToolbox + 0x23d3 11:30:46 INFO - HIToolbox + 0x958f 11:30:46 INFO - HIToolbox + 0x94ca 11:30:46 INFO - AppKit + 0x93f1 11:30:46 INFO - -AppKit + 0x8cf5 11:30:46 INFO - -[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [widget/cocoa/nsAppShell.mm:164] 11:30:46 INFO - nsAppShell::ProcessNextNativeEvent(bool) [widget/cocoa/nsAppShell.mm:667] 11:30:46 INFO - nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool, unsigned int) [widget/xpwidgets/nsBaseAppShell.cpp:139] 11:30:46 INFO - _ZThn8_N10nsAppShell18OnProcessNextEventEP17nsIThreadInternalbj [widget/cocoa/nsAppShell.mm:831] 11:30:46 INFO - nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:602] 11:30:46 INFO - NS_ProcessPendingEvents_P(nsIThread*, unsigned int) [obj-firefox/xpcom/build/nsThreadUtils.cpp:188] 11:30:46 INFO - nsBaseAppShell::NativeEventCallback() [widget/xpwidgets/nsBaseAppShell.cpp:98] 11:30:46 INFO - nsAppShell::ProcessGeckoEvents(void*) [widget/cocoa/nsAppShell.mm:388] 11:30:46 INFO - CoreFoundation + 0x12b51 11:30:46 INFO - CoreFoundation + 0x123bd 11:30:46 INFO - CoreFoundation + 0x391a9 11:30:46 INFO - CoreFoundation + 0x38ae6 11:30:46 INFO - HIToolbox + 0x23d3 11:30:46 INFO - HIToolbox + 0x963d 11:30:46 INFO - HIToolbox + 0x94ca 11:30:46 INFO - AppKit + 0x93f1 11:30:46 INFO - -AppKit + 0x8cf5 11:30:46 INFO - -[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [widget/cocoa/nsAppShell.mm:164] 11:30:46 INFO - -AppKit + 0x562d 11:30:46 INFO - nsAppShell::Run() [widget/cocoa/nsAppShell.mm:741] 11:30:46 INFO - nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:288] 11:30:46 INFO - XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:3885] 11:30:46 INFO - XREMain::XRE_main(int, char**, nsXREAppData const*) [toolkit/xre/nsAppRunner.cpp:3952] 11:30:46 INFO - XRE_main [toolkit/xre/nsAppRunner.cpp:4155] 11:30:46 INFO - main [browser/app/nsBrowserApp.cpp:224] 11:30:46 INFO - [Parent 464] ###!!! ASSERTION: Didn't find the locked file we were looking for!: 'mLockedFileQueues.Length() == lockedFileCount - 1', file ../../../dom/file/FileService.cpp, line 467 11:30:46 INFO - mozilla::dom::file::FileService::NotifyLockedFileCompleted(mozilla::dom::file::LockedFile*) [obj-firefox/dist/include/nsTArray.h:277] 11:30:46 INFO - mozilla::dom::file::FinishHelper::Run() [obj-firefox/dist/include/nsCOMPtr.h:520] 11:30:46 INFO - nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:627] 11:30:46 INFO - NS_ProcessPendingEvents_P(nsIThread*, unsigned int) [obj-firefox/xpcom/build/nsThreadUtils.cpp:188] 11:30:46 INFO - nsBaseAppShell::NativeEventCallback() [widget/xpwidgets/nsBaseAppShell.cpp:98] 11:30:46 INFO - nsAppShell::ProcessGeckoEvents(void*) [widget/cocoa/nsAppShell.mm:388] 11:30:46 INFO - CoreFoundation + 0x12b51 11:30:46 INFO - CoreFoundation + 0x123bd 11:30:46 INFO - CoreFoundation + 0x391a9 11:30:46 INFO - CoreFoundation + 0x38ae6 11:30:46 INFO - HIToolbox + 0x23d3 11:30:46 INFO - HIToolbox + 0x958f 11:30:46 INFO - HIToolbox + 0x94ca 11:30:46 INFO - AppKit + 0x93f1 11:30:46 INFO - -AppKit + 0x8cf5 11:30:46 INFO - -[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [widget/cocoa/nsAppShell.mm:164] 11:30:46 INFO - nsAppShell::ProcessNextNativeEvent(bool) [widget/cocoa/nsAppShell.mm:667] 11:30:46 INFO - nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool, unsigned int) [widget/xpwidgets/nsBaseAppShell.cpp:139] 11:30:46 INFO - _ZThn8_N10nsAppShell18OnProcessNextEventEP17nsIThreadInternalbj [widget/cocoa/nsAppShell.mm:831] 11:30:46 INFO - nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:602] 11:30:46 INFO - NS_ProcessPendingEvents_P(nsIThread*, unsigned int) [obj-firefox/xpcom/build/nsThreadUtils.cpp:188] 11:30:46 INFO - nsBaseAppShell::NativeEventCallback() [widget/xpwidgets/nsBaseAppShell.cpp:98] 11:30:46 INFO - nsAppShell::ProcessGeckoEvents(void*) [widget/cocoa/nsAppShell.mm:388] 11:30:46 INFO - CoreFoundation + 0x12b51 11:30:46 INFO - CoreFoundation + 0x123bd 11:30:46 INFO - CoreFoundation + 0x391a9 11:30:46 INFO - CoreFoundation + 0x38ae6 11:30:46 INFO - HIToolbox + 0x23d3 11:30:46 INFO - HIToolbox + 0x963d 11:30:46 INFO - HIToolbox + 0x94ca 11:30:46 INFO - AppKit + 0x93f1 11:30:46 INFO - -AppKit + 0x8cf5 11:30:46 INFO - -[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] [widget/cocoa/nsAppShell.mm:164] 11:30:46 INFO - -AppKit + 0x562d 11:30:46 INFO - nsAppShell::Run() [widget/cocoa/nsAppShell.mm:741] 11:30:46 INFO - nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:288] 11:30:46 INFO - XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:3885] 11:30:46 INFO - XREMain::XRE_main(int, char**, nsXREAppData const*) [toolkit/xre/nsAppRunner.cpp:3952] 11:30:46 INFO - XRE_main [toolkit/xre/nsAppRunner.cpp:4155] 11:30:46 INFO - main [browser/app/nsBrowserApp.cpp:224] 11:30:46 INFO - 3871 INFO TEST-END | /tests/dom/file/test/test_readonly_lockedfiles.html | finished in 215ms 11:30:46 INFO - ++DOMWINDOW == 64 (0x154caab78) [serial = 2024] [outer = 0x11d205858] 11:30:46 INFO - 3872 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/file/test/test_readonly_lockedfiles.html | Assertion count 2 is greater than expected range 0-0 assertions. 11:53:54 INFO - 17101 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_put.html | Test timed out. 11:59:24 INFO - 17104 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_twice_failure.html | Test timed out. 12:04:54 INFO - 17107 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_advance.html | Test timed out. 12:10:24 INFO - 17110 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_app_isolation_inproc.html | Test timed out.

Ryan VanderMeulen [:RyanVM]

Reporter

Comment 1

•

12 years ago

https://tbpl.mozilla.org/php/getParsedLog.php?id=20238698&full=1&branch=mozilla-inbound

Comment hidden (Legacy TBPL/Treeherder Robot)

Phil Ringnalda (:philor)

Comment 7

•

12 years ago

Looks to be permaorange on Win8, too.

OS: Mac OS X → All

Hardware: x86_64 → All

Ben Turner (not reading bugmail, use the needinfo flag!)

Comment 8

•

12 years ago

Jan, any ideas here?

Comment hidden (Legacy TBPL/Treeherder Robot)

philor https://tbpl.mozilla.org/php/getParsedLog.php?id=20647028&tree=Firefox Rev3 WINNT 6.2 mozilla-central debug test mochitest-2 on 2013-03-14 06:49:15 slave: t-w864-ix-089 06:52:39 INFO - 63 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_grandchild.html | Assertion count 1 is greater than expected range 0-0 assertions. 06:52:46 INFO - 69 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_not-opener.html | Assertion count 1 is greater than expected range 0-0 assertions. 06:52:51 INFO - 76 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_opener.html | Assertion count 1 is greater than expected range 0-0 assertions. 06:52:56 INFO - 83 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_popup-navigates-children.html | Assertion count 1 is greater than expected range 0-0 assertions. 06:53:07 INFO - 94 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_reserved.html | Assertion count 2 is greater than expected range 0-0 assertions. 06:58:52 INFO - 3882 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/file/test/test_readonly_lockedfiles.html | Assertion count 3 is greater than expected range 0-0 assertions. 07:20:59 INFO - 17111 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_put.html | Test timed out. 07:26:29 INFO - 17114 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_twice_failure.html | Test timed out. 07:31:59 INFO - 17117 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_advance.html | Test timed out. 07:37:29 INFO - 17120 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_app_isolation_inproc.html | Test timed out. 07:37:29 INFO - 17121 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. 07:37:29 INFO - 17122 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 108 remaining tests. 07:37:30 INFO - 17123 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | /tests/dom/indexedDB/test/test_app_isolation_inproc.html finished in a non-clean fashion, probably because it didn't call SimpleTest.finish() 07:45:51 WARNING - TEST-UNEXPECTED-FAIL | Shutdown | application timed out after 330 seconds with no output 07:45:59 INFO - PROCESS-CRASH | Shutdown | application crashed [@ CrashingThread(void *)] 07:46:01 ERROR - Return code: 1

Comment hidden (Legacy TBPL/Treeherder Robot)

philor https://tbpl.mozilla.org/php/getParsedLog.php?id=20648024&tree=Mozilla-Inbound Rev3 WINNT 6.2 mozilla-inbound debug test mochitest-2 on 2013-03-14 07:29:13 slave: t-w864-ix-066 07:33:08 INFO - 68 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_not-opener.html | Assertion count 1 is greater than expected range 0-0 assertions. 07:33:12 INFO - 75 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_opener.html | Assertion count 1 is greater than expected range 0-0 assertions. 07:33:17 INFO - 82 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_popup-navigates-children.html | Assertion count 1 is greater than expected range 0-0 assertions. 07:33:29 INFO - 93 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_reserved.html | Assertion count 1 is greater than expected range 0-0 assertions. 07:39:16 INFO - 3881 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/file/test/test_readonly_lockedfiles.html | Assertion count 4 is greater than expected range 0-0 assertions. 08:01:17 INFO - 17110 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_put.html | Test timed out. 08:06:47 INFO - 17113 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_twice_failure.html | Test timed out. 08:12:17 INFO - 17116 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_advance.html | Test timed out. 08:17:47 INFO - 17119 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_app_isolation_inproc.html | Test timed out. 08:17:47 INFO - 17120 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. 08:17:47 INFO - 17121 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 108 remaining tests. 08:17:48 INFO - 17122 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | /tests/dom/indexedDB/test/test_app_isolation_inproc.html finished in a non-clean fashion, probably because it didn't call SimpleTest.finish() 08:26:08 WARNING - TEST-UNEXPECTED-FAIL | Shutdown | application timed out after 330 seconds with no output 08:26:16 INFO - PROCESS-CRASH | Shutdown | application crashed [@ CrashingThread(void *)] 08:26:19 ERROR - Return code: 1

Comment hidden (Legacy TBPL/Treeherder Robot)

RyanVM https://tbpl.mozilla.org/php/getParsedLog.php?id=20648707&tree=Firefox Rev3 WINNT 6.2 mozilla-central debug test mochitest-2 on 2013-03-14 08:09:19 slave: t-w864-ix-017 08:12:48 INFO - 57 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_child.html | Assertion count 1 is greater than expected range 0-0 assertions. 08:12:50 INFO - 64 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_grandchild.html | Assertion count 1 is greater than expected range 0-0 assertions. 08:12:57 INFO - 70 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_not-opener.html | Assertion count 1 is greater than expected range 0-0 assertions. 08:13:02 INFO - 77 ERROR TEST-UNEXPECTED-FAIL | /tests/docshell/test/navigation/test_opener.html | Assertion count 1 is greater than expected range 0-0 assertions. 08:19:09 INFO - 3881 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/file/test/test_readonly_lockedfiles.html | Assertion count 2 is greater than expected range 0-0 assertions. 08:41:11 INFO - 17110 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_put.html | Test timed out. 08:46:41 INFO - 17113 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_add_twice_failure.html | Test timed out. 08:52:12 INFO - 17116 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_advance.html | Test timed out. 08:57:42 INFO - 17119 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/indexedDB/test/test_app_isolation_inproc.html | Test timed out. 08:57:42 INFO - 17120 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. 08:57:42 INFO - 17121 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 108 remaining tests. 08:57:43 INFO - 17122 ERROR TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | /tests/dom/indexedDB/test/test_app_isolation_inproc.html finished in a non-clean fashion, probably because it didn't call SimpleTest.finish() 09:06:03 WARNING - TEST-UNEXPECTED-FAIL | Shutdown | application timed out after 330 seconds with no output 09:06:11 INFO - PROCESS-CRASH | Shutdown | application crashed [@ CrashingThread(void *)] 09:06:13 ERROR - Return code: 1

Comment hidden (Legacy TBPL/Treeherder Robot)

Jan Varga [:janv]

Assignee

Comment 50

•

12 years ago

Attached patch fix — Details — Splinter Review

Assignee: nobody → Jan.Varga

Status: NEW → ASSIGNED

Attachment #725900 - Flags: review?(bent.mozilla)

Comment hidden (Legacy TBPL/Treeherder Robot)

Ben Turner (not reading bugmail, use the needinfo flag!)

Comment 52

•

12 years ago

Comment on attachment 725900 [details] [diff] [review] fix Review of attachment 725900 [details] [diff] [review]: ----------------------------------------------------------------- Nice! Thanks for finding this.

Attachment #725900 - Flags: review?(bent.mozilla) → review+

Jan Varga [:janv]

Assignee

Comment 53

•

12 years ago

http://hg.mozilla.org/integration/mozilla-inbound/rev/ba70784af08b

Ed Morley [:emorley]

Comment 54

•

12 years ago

https://hg.mozilla.org/mozilla-central/rev/ba70784af08b

Status: ASSIGNED → RESOLVED

Closed: 12 years ago

Resolution: --- → FIXED

Target Milestone: --- → mozilla22

Comment hidden (Legacy TBPL/Treeherder Robot)

Jan Varga [:janv]

Assignee

Comment 56

•

12 years ago

I guess the Build-System tree doesn't contain the fix yet.

Phil Ringnalda (:philor)

Comment 57

•

12 years ago

I don't exactly star it in a prompt manner - that failed run started 45 minutes before the patch even hit mozilla-central (and yay for tbpl caching, didn't show this bug as being fixed, since it wasn't at the time tbpl first looked at the log).