Closed Bug 966438 Opened 6 years ago Closed 6 years ago

Intermittent test_bug650995.js | test failed (with xpcshell return code: 0) | "012345" == "Initial value" | Error in closure function: 2147500036

Categories

(Core :: Networking: Cache, defect)

ARM
Android
defect
Not set

Tracking

()

RESOLVED FIXED
mozilla30
Tracking Status
firefox28 --- unaffected
firefox29 --- wontfix
firefox30 --- fixed
firefox-esr24 --- unaffected

People

(Reporter: RyanVM, Assigned: michal)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

https://tbpl.mozilla.org/php/getParsedLog.php?id=33894596&tree=Mozilla-Inbound

Android 4.0 Panda mozilla-inbound opt test xpcshell on 2014-01-31 11:39:06 PST for push 78bbd153072a
slave: panda-0297

12:08:38     INFO -  TEST-INFO | /builds/panda-0297/test/build/tests/xpcshell/tests/netwerk/test/unit/test_bug650995.js | running test ...
12:08:41  WARNING -  TEST-UNEXPECTED-FAIL | /builds/panda-0297/test/build/tests/xpcshell/tests/netwerk/test/unit/test_bug650995.js | test failed (with xpcshell return code: 0), see following log:
12:08:41     INFO -  >>>>>>>
12:08:41     INFO -  xpcw: cd /mnt/sdcard/tests/xpcshell/netwerk/test/unit
12:08:41     INFO -  xpcw: xpcshell -r /mnt/sdcard/tests/xpcshell/c/httpd.manifest --greomni /data/local/xpcb/fennec-29.0a1.en-US.android-arm.apk -m -n -s -e const _HTTPD_JS_PATH = "/mnt/sdcard/tests/xpcshell/c/httpd.js"; -e const _HEAD_JS_PATH = "/mnt/sdcard/tests/xpcshell/head.js"; -e const _TESTING_MODULES_DIR = "/mnt/sdcard/tests/xpcshell/m"; -f /mnt/sdcard/tests/xpcshell/head.js -e const _SERVER_ADDR = "localhost" -e const _HEAD_FILES = ["/mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js", "/mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js", "/mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache2.js"]; -e const _TAIL_FILES = []; -e const _TEST_FILE = ["test_bug650995.js"]; -e _execute_test(); quit(0);
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test pending (1)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test finished (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | running event loop
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  System JS : WARNING test_bug650995.js:82 - assignment to undeclared variable cap
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 13 == 13
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 6 == 6
12:08:41     INFO -  TEST-PASS | test_bug650995.js | [TestCacheEntrySize/this.initialLoad : 127] "012345" == "012345"
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 6 == 6
12:08:41     INFO -  TEST-PASS | test_bug650995.js | [TestCacheEntrySize/this.testAndTriggerNext : 135] "012345" == "012345"
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test nextTest pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test nextTest finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 1408 == 1408
12:08:41     INFO -  TEST-PASS | test_bug650995.js | [TestCacheEntrySize/this.initialLoad : 127] "0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a" == "0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a"
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 10 == 10
12:08:41     INFO -  TEST-PASS | test_bug650995.js | [TestCacheEntrySize/this.testAndTriggerNext : 135] "9876543210" == "9876543210"
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test nextTest pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test nextTest finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 1408 == 1408
12:08:41     INFO -  TEST-PASS | test_bug650995.js | [TestCacheEntrySize/this.initialLoad : 127] "0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a" == "0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a"
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 1408 == 1408
12:08:41     INFO -  TEST-PASS | test_bug650995.js | [TestCacheEntrySize/this.testAndTriggerNext : 135] "0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a" == "0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a0123456789a"
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test nextTest pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test nextTest finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 13 == 13
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_cache.js | [syncWithCacheIOThread/< : 103] 2152398909 == 2152398909
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | test finished (2)
12:08:41     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | [ChannelListener.prototype.onStopRequest : 162] 13 == 13
12:08:41  WARNING -  TEST-UNEXPECTED-FAIL | test_bug650995.js | "012345" == "Initial value" - See following stack:
12:08:41     INFO -  JS frame :: test_bug650995.js :: TestCacheEntrySize/this.initialLoad :: line 127
12:08:41     INFO -  JS frame :: /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js :: ChannelListener.prototype.onStopRequest :: line 167
12:08:41     INFO -  native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | exiting test
12:08:41  WARNING -  TEST-UNEXPECTED-FAIL | /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js | Error in closure function: 2147500036 - See following stack:
12:08:41     INFO -  JS frame :: /mnt/sdcard/tests/xpcshell/netwerk/test/unit/head_channels.js :: ChannelListener.prototype.onStopRequest :: line 169
12:08:41     INFO -  native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
12:08:41     INFO -  TEST-INFO | (xpcshell/head.js) | exiting test
12:08:41     INFO -  System JS : ERROR (null):0 - uncaught exception: 2147500036
12:08:41     INFO -  !!! error running onStopped callback: TypeError: callback is not a function
12:08:41     INFO -  \x00
12:08:41     INFO -  <<<<<<<
The problem is that the new test is run before the files on disk are removed. Bug 968106 will fix this.
Depends on: 968106
Attached patch patch v1Splinter Review
The test use nsICacheStorage.asyncEvictStorage() instead of nsICacheStorageService.clear() which was fixed in bug #968106. nsICacheStorage.asyncEvictStorage() is still not implemented for disk storage.

We should probably consider doing the same change also in other tests that use evict_cache_entries() to clear the cache since nsICacheStorageService.clear() will be always faster than nsICacheStorage.asyncEvictStorage() and it is also more correct since evict_cache_entries() evicts only the default LoadContextInfo.
Attachment #8388596 - Flags: review?(honzab.moz)
Attachment #8388596 - Flags: review?(honzab.moz) → review+
https://hg.mozilla.org/mozilla-central/rev/7311786f8477
Assignee: nobody → michal.novotny
Status: NEW → RESOLVED
Closed: 6 years ago
Flags: in-testsuite+
Resolution: --- → FIXED
Target Milestone: --- → mozilla30
It appears that this started when trunk will still on 29. In that case, please request Aurora approval for this patch if it's reasonable to do so.
Flags: needinfo?(michal.novotny)
This test fails very often with cache2 enabled and this patch actually fixes just cache2. I have no idea why it fails also with cache2 disabled.
Flags: needinfo?(michal.novotny)
You need to log in before you can comment on or make changes to this bug.