Closed Bug 1789607 Opened 2 years ago Closed 2 years ago

High frequency toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | single tracking bug

Categories

(Toolkit :: Add-ons Manager, defect)

defect

Tracking

()

RESOLVED FIXED
111 Branch
Tracking Status
firefox111 --- fixed

People

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

References

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [stockwell disable-recommended][addons-jira])

Attachments

(2 files)

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=389723451&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TI0aJcpJToiJsGAfdbOZOA/runs/0/artifacts/public/logs/live_backing.log


[task 2022-09-07T10:54:31.074Z] 10:54:31     INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | Should have seen the right message - 
[task 2022-09-07T10:54:31.075Z] 10:54:31     INFO - Waiting for addon-webext-permissions notification
[task 2022-09-07T10:54:31.075Z] 10:54:31     INFO - Buffered messages logged at 10:48:30
[task 2022-09-07T10:54:31.076Z] 10:54:31     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 3
[task 2022-09-07T10:54:31.076Z] 10:54:31     INFO - Buffered messages logged at 10:50:00
[task 2022-09-07T10:54:31.077Z] 10:54:31     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 2
[task 2022-09-07T10:54:31.077Z] 10:54:31     INFO - Buffered messages logged at 10:51:30
[task 2022-09-07T10:54:31.077Z] 10:54:31     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2022-09-07T10:54:31.078Z] 10:54:31     INFO - Buffered messages logged at 10:52:58
[task 2022-09-07T10:54:31.078Z] 10:54:31     INFO - Console message: [JavaScript Error: "Invalid ETag value "undefined"" {file: "resource://services-settings/SyncHistory.jsm" line: 52}]
[task 2022-09-07T10:54:31.078Z] 10:54:31     INFO - store@resource://services-settings/SyncHistory.jsm:52:13
[task 2022-09-07T10:54:31.078Z] 10:54:31     INFO - 
[task 2022-09-07T10:54:31.079Z] 10:54:31     INFO - Buffered messages finished
[task 2022-09-07T10:54:31.079Z] 10:54:31     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | Test timed out - 
[task 2022-09-07T10:54:31.080Z] 10:54:31     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-07T10:54:31.081Z] 10:54:31     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | No unexamined telemetry events after test is finished - [] deepEqual [{"method":"install","object":"unknown","value":"26","extra":{"step":"started","source":"test-host","method":"installTrigger","install_origins":"0"}},{"method":"install","object":"unknown","value":"26","extra":{"step":"download_started","source":"test-host","method":"installTrigger","install_origins":"0"}},{"method":"install","object":"extension","value":"26","extra":{"step":"download_completed","download_time":"59","source":"test-host","method":"installTrigger","addon_id":"amosigned-xpi@tests.mozilla.org","install_origins":"0"}},{"method":"install","object":"extension","value":"26","extra":{"step":"site_warning","source":"test-host","method":"installTrigger","addon_id":"amosigned-xpi@tests.mozilla.org","install_origins":"0"}}] - JS frame :: resource://testing-common/AddonTestUtils.jsm :: hookAMTelemetryEvents/< :: line 1794
[task 2022-09-07T10:54:31.081Z] 10:54:31     INFO - Stack trace:
[task 2022-09-07T10:54:31.081Z] 10:54:31     INFO - resource://testing-common/AddonTestUtils.jsm:hookAMTelemetryEvents/<:1794
[task 2022-09-07T10:54:31.082Z] 10:54:31     INFO - chrome://mochikit/content/browser-test.js:nextTest:599
[task 2022-09-07T10:54:31.082Z] 10:54:31     INFO - Console message: 1662548070823	addons.xpi	DEBUG	Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
[task 2022-09-07T10:54:31.082Z] 10:54:31     INFO - Console message: 1662548070834	addons.xpi	DEBUG	removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file C:\Users\task_166254430125991\AppData\Local\Temp\tmp-7si.xpi
[task 2022-09-07T10:54:31.215Z] 10:54:31     INFO - GECKO(2940) | MEMORY STAT | vsize 981MB | vsizeMaxContiguous 1293MB | residentFast 256MB | heapAllocated 87MB
[task 2022-09-07T10:54:31.217Z] 10:54:31     INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | took 450475ms
[task 2022-09-07T10:54:31.218Z] 10:54:31     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-07T10:54:31.219Z] 10:54:31     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22XPI%22%3A%22amosigned.xpi%22%7D - 
[task 2022-09-07T10:54:31.237Z] 10:54:31     INFO - GECKO(2940) | [Child 9084, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-09-07T10:54:31.250Z] 10:54:31     INFO - GECKO(2940) | [Child 8912: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL a2de600 == 1 [pid = 8912] [id = 12]
[task 2022-09-07T10:54:31.255Z] 10:54:31     INFO - GECKO(2940) | [Child 8912: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (54a4130) [pid = 8912] [serial = 25] [outer = 0]
[task 2022-09-07T10:54:31.255Z] 10:54:31     INFO - GECKO(2940) | [Child 8912: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (a2df800) [pid = 8912] [serial = 26] [outer = 54a4130]
[task 2022-09-07T10:54:31.264Z] 10:54:31     INFO - GECKO(2940) | [Child 9104, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-09-07T10:54:31.278Z] 10:54:31     INFO - checking window state
[task 2022-09-07T10:54:31.319Z] 10:54:31     INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_empty.js

Started to be high frequency after Bug 1799009 was pushed - retriggers and backfills.

Summary: Intermittent toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | single tracking bug → High frequency toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js | single tracking bug

Hi William! Could you please check out this high frequency failure? It looks that it started to be high frequency after Bug 1799009 was pushed, as comment.
Thank you!

There have been 149 total failures in the last 7 days, recent failure log.

Affected platforms are:

  • macosx1015-64-qr
  • macosx1015-64-shippable-qr
  • windows7-32-qr
  • windows7-32-shippable-qr
Flags: needinfo?(wdurand)
Assignee: nobody → sstanca
Status: NEW → ASSIGNED

This bug has 177 total failures in the last 30 days and made a disable patch until there's a fix in place.

Status: ASSIGNED → NEW
Assignee: sstanca → nobody
Assignee: nobody → sstanca
Status: NEW → ASSIGNED

I started to investigate this intermittent last week, I will submit a patch shortly.

Flags: needinfo?(wdurand)
Whiteboard: [stockwell disable-recommended] → [stockwell disable-recommended][addons-jira]
Assignee: sstanca → wdurand
Pushed by wdurand@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/ebf74fc8d01c Fix high frequency toolkit/mozapps/extensions/test/xpinstall/browser_doorhanger_installs.js. r=robwu
Status: ASSIGNED → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 111 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: