Closed Bug 1872740 Opened 9 months ago Closed 9 months ago

Intermittent TV dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | xpcshell return code: 0 | Test timed out

Categories

(Core :: Storage: IndexedDB, defect, P5)

Product:
Core
Component:
Storage: IndexedDB
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
123 Branch
Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox121 --- unaffected
firefox122 --- unaffected
firefox123 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jjalkanen)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

Attachments

(1 file)

Bug 1872740 - Fix chaos mode performance test timeout. r=#dom-storage
48 bytes, text/x-phabricator-request
Details | Review

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=441930175&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/I--X933oRMqQkfY5nbxDuQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/I--X933oRMqQkfY5nbxDuQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2024-01-02T17:18:52.291Z] 17:18:52     INFO -  TEST-START | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js
[task 2024-01-02T17:23:19.853Z] 17:23:19  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | xpcshell return code: 0
[task 2024-01-02T17:23:19.853Z] 17:23:19     INFO -  TEST-INFO took 267561ms
[task 2024-01-02T17:23:19.853Z] 17:23:19     INFO -  >>>>>>>
[task 2024-01-02T17:23:19.853Z] 17:23:19     INFO -  PID 5204 | *** You are running in chaos test mode. See ChaosMode.h. ***
[task 2024-01-02T17:23:19.854Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2886
[task 2024-01-02T17:23:19.854Z] 17:23:19     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2024-01-02T17:23:19.855Z] 17:23:19     INFO -  TEST-PASS | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | this.runTest - [this.runTest : 52] There should be a testSteps function - true == true
[task 2024-01-02T17:23:19.856Z] 17:23:19     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2024-01-02T17:23:19.856Z] 17:23:19     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2024-01-02T17:23:19.856Z] 17:23:19     INFO -  running event loop
[task 2024-01-02T17:23:19.857Z] 17:23:19     INFO -  PID 5204 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2024-01-02T17:23:19.857Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:402
[task 2024-01-02T17:23:19.857Z] 17:23:19     INFO -  xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | Starting testSteps
[task 2024-01-02T17:23:19.858Z] 17:23:19     INFO -  (xpcshell/head.js) | test testSteps pending (2)
[task 2024-01-02T17:23:19.858Z] 17:23:19     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2024-01-02T17:23:19.858Z] 17:23:19     INFO -  "CONSOLE_MESSAGE: (info) No chrome package registered for chrome://branding/locale/brand.properties"
[task 2024-01-02T17:23:19.858Z] 17:23:19     INFO -  TEST-PASS | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | testSteps - [testSteps : 20] Correct byte length - 512 == 512
[task 2024-01-02T17:23:19.859Z] 17:23:19     INFO -  TEST-PASS | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | testSteps - [testSteps : 20] Correct byte length - 512 == 512
[task 2024-01-02T17:23:19.859Z] 17:23:19     INFO -  TEST-PASS | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | testSteps - [testSteps : 20] Correct byte length - 512 == 512
[task 2024-01-02T17:23:19.860Z] 17:23:19     INFO -  TEST-PASS | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | testSteps - [testSteps : 46] Are all buffers different? - true == true
[task 2024-01-02T17:23:19.860Z] 17:23:19     INFO -  TEST-PASS | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | testSteps - [testSteps : 50] Are all buffers different? - true == true
[task 2024-01-02T17:23:19.860Z] 17:23:19     INFO -  TEST-PASS | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | testSteps - [testSteps : 51] Are all buffers different? - true == true
[task 2024-01-02T17:23:19.863Z] 17:23:19     INFO -  Unexpected exception Error: Performance regression detected at /builds/worker/workspace/build/tests/xpcshell/tests/dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js:100
[task 2024-01-02T17:23:19.864Z] 17:23:19     INFO -  testSteps/</<@/builds/worker/workspace/build/tests/xpcshell/tests/dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js:100:13
[task 2024-01-02T17:23:19.864Z] 17:23:19     INFO -  notify@/builds/worker/workspace/build/tests/xpcshell/head.js:218:20
[task 2024-01-02T17:23:19.864Z] 17:23:19     INFO -  _do_main@/builds/worker/workspace/build/tests/xpcshell/head.js:245:6
[task 2024-01-02T17:23:19.865Z] 17:23:19     INFO -  _execute_test@/builds/worker/workspace/build/tests/xpcshell/head.js:592:5
[task 2024-01-02T17:23:19.865Z] 17:23:19     INFO -  @-e:1:1
[task 2024-01-02T17:23:19.865Z] 17:23:19     INFO -  exiting test
[task 2024-01-02T17:23:19.865Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.866Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.866Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.866Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.866Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.867Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.867Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.867Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.867Z] 17:23:19     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:9243"]"
[task 2024-01-02T17:23:19.868Z] 17:23:19     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "An IndexedDB transaction that was not yet complete has been aborted due to page navigation." {file: "/builds/worker/workspace/build/tests/xpcshell/tests/dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js" line: 25}]"
[task 2024-01-02T17:23:19.868Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '35.344000s: killActorsTimerCallback', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.868Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: idb: '35.356000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.868Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: idb: '35.356000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.869Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, QuotaManager IO] WARNING: quota manager shutdown step: '35.356000s: ShutdownStorageOp::DoDirectoryWork -> ShutdownStorageInternal.', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.869Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '35.356000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.871Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '35.356000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.871Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: idb: '35.356000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.872Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: cache: '35.360000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.872Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: sdb: '35.360000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.872Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: fs: '35.360000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.872Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: ls: '35.360000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.872Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '35.360000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.873Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '35.360000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.873Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, IPDL Background] WARNING: quota manager shutdown step: '35.360000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-01-02T17:23:19.873Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:3376
[task 2024-01-02T17:23:19.874Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, Main Thread] WARNING: XPCOM object PerThreadData destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2024-01-02T17:23:19.874Z] 17:23:19     INFO -  PID 5204 | [Parent 5204, Main Thread] WARNING: XPCOM object SourceList destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2024-01-02T17:23:19.874Z] 17:23:19     INFO -  <<<<<<<
[task 2024-01-02T17:23:19.874Z] 17:23:19     INFO -  TEST-START | xpcshell.toml:dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js

:jjalkanen, since you are the author of the regressor, bug 1860486, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(jjalkanen)

Here is the failure log for OSX Tv failure: https://treeherder.mozilla.org/logviewer?job_id=441930310&repo=autoland&lineNumber=3997

Summary: Intermittent TV dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | xpcshell return code: 0 → Intermittent TV dom/indexedDB/test/unit/test_clear_object_store_with_indexes.js | xpcshell return code: 0 | Test timed out

Set release status flags based on info from the regressing bug 1860486

status-firefox121: --- → unaffected
status-firefox122: --- → unaffected
status-firefox123: --- → affected
status-firefox-esr115: --- → unaffected

The test times out only in chaos mode, I'll take a look.

Assignee: nobody → jjalkanen
Flags: needinfo?(jjalkanen)

The amount of data is reduced in chaos mode to catch timing anomalies.
With the usual amount of data, chaos mode slowdowns do not allow the
test to pass within a reasonable timeout.

Attachment #9371243 - Attachment description: Bug 1872740 - Fix chaos mode performance test timeout on mac. r=#dom-storage → Bug 1872740 - Fix chaos mode performance test timeout. r=#dom-storage
Pushed by jjalkanen@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/fe856f6e04a4 Fix chaos mode performance test timeout. r=dom-storage-reviewers,janv

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

Status: NEW → RESOLVED
Closed: 9 months ago
status-firefox123: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 123 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: