Open Bug 1824259 Opened 2 years ago Updated 1 day ago

Intermittent browser/base/content/test/webextensions/browser_extension_update_background.js | single tracking bug

Categories

(Firefox :: Tabbed Browser, defect, P5)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [retriggered])

Attachments

(1 obsolete file)

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


[task 2023-03-23T21:48:50.304Z] 21:48:50     INFO - TEST-PASS | browser/base/content/test/webextensions/browser_extension_update_background.js | Should have upgraded to the new version - 
[task 2023-03-23T21:48:50.305Z] 21:48:50     INFO - TEST-PASS | browser/base/content/test/webextensions/browser_extension_update_background.js | Addon alert badge should be gone - 
[task 2023-03-23T21:48:50.305Z] 21:48:50     INFO - Console message: 1679608129472	addons.xpi	WARN	Unexpected missing XPI state for add-on update_icon2@tests.mozilla.org
[task 2023-03-23T21:48:50.306Z] 21:48:50     INFO - Buffered messages finished
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/webextensions/browser_extension_update_background.js | Got the steps from the collected telemetry events - ["started","download_started","download_completed","permissions_prompt","cancelled","started","download_started","download_completed","permissions_prompt","started","download_started","completed","download_completed","completed"] deepEqual ["started","download_started","download_completed","permissions_prompt","cancelled","started","download_started","download_completed","permissions_prompt","completed"] - {"filename":"chrome://mochitests/content/browser/browser/base/content/test/webextensions/browser_extension_update_background.js","name":"backgroundUpdateTest","sourceId":717,"lineNumber":211,"columnNumber":10,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochitests/content/browser/browser/base/content/test/webextensions/browser_extension_update_background.js","name":null,"sourceId":717,"lineNumber":277,"columnNumber":23,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"handleTask","sourceId":521,"lineNumber":1039,"columnNumber":26,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"_runTaskBasedTest","sourceId":521,"lineNumber":1111,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":521,"lineNumber":1253,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":521,"lineNumber":1028,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":548,"lineNumber":1053,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1028:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13\n","nativeSavedFrame":{}},"formattedStack":"async*Tester_execTest@chrome://mochikit/content/browser-test.js:1253:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1028:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1111:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1253:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1028:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13\n","nativeSavedFrame":{}},"formattedStack":"handleTask@chrome://mochikit/content/browser-test.js:1039:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1111:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1253:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1028:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13\n","nativeSavedFrame":{}},"formattedStack":"async*@chrome://mochitests/content/browser/browser/base/content/test/webextensions/browser_extension_update_background.js:277:23\nhandleTask@chrome://mochikit/content/browser-test.js:1039:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1111:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1253:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1028:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"backgroundUpdateTest@chrome://mochitests/content/browser/browser/base/content/test/webextensions/browser_extension_update_background.js:211:10\nasync*@chrome://mochitests/content/browser/browser/base/content/test/webextensions/browser_extension_update_background.js:277:23\nhandleTask@chrome://mochikit/content/browser-test.js:1039:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1111:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1253:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1028:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13\n","nativeSavedFrame":{}}
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - Stack trace:
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - chrome://mochitests/content/browser/browser/base/content/test/webextensions/browser_extension_update_background.js:backgroundUpdateTest:211
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - chrome://mochitests/content/browser/browser/base/content/test/webextensions/browser_extension_update_background.js:null:277
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - chrome://mochikit/content/browser-test.js:handleTask:1039
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1111
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1253
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1028
[task 2023-03-23T21:48:50.312Z] 21:48:50     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1053
[task 2023-03-23T21:48:50.315Z] 21:48:50     INFO - TEST-PASS | browser/base/content/test/webextensions/browser_extension_update_background.js | Got the expected permission_prompts events - [{"method":"update","object":"extension","extra":{"step":"permissions_prompt","num_strings":"1","source":"fake-install-source","addon_id":"update_icon2@tests.mozilla.org","updated_from":"app"}},{"method":"update","object":"extension","extra":{"step":"permissions_prompt","num_strings":"1","source":"fake-install-source","addon_id":"update_icon2@tests.mozilla.org","updated_from":"app"}}] deepEqual [{"method":"update","object":"extension","extra":{"addon_id":"update_icon2@tests.mozilla.org","source":"fake-install-source","step":"permissions_prompt","updated_from":"app","num_strings":"1"}},{"method":"update","object":"extension","extra":{"addon_id":"update_icon2@tests.mozilla.org","source":"fake-install-source","step":"permissions_prompt","updated_from":"app","num_strings":"1"}}] - 
[task 2023-03-23T21:48:50.316Z] 21:48:50     INFO - Leaving test bound 
[task 2023-03-23T21:48:50.318Z] 21:48:50     INFO - Console message: 1679608129655	addons.xpi	WARN	Exception running bootstrap method update on update_icon2@tests.mozilla.org: Error: Error while loading 'jar:file:///tmp/tmp2350e124.mozrunner/extensions/update_icon2@tests.mozilla.org.xpi!/manifest.json' (NS_ERROR_FAILURE)(resource://gre/modules/Extension.jsm:824:20) JS Stack trace: readJSON/</<@Extension.jsm:824:20
[task 2023-03-23T21:48:50.318Z] 21:48:50     INFO - onStopRequest@NetUtil.jsm:126:18
[task 2023-03-23T21:48:50.319Z] 21:48:50     INFO - Console message: [JavaScript Error: "Error while loading 'jar:file:///tmp/tmp2350e124.mozrunner/extensions/update_icon2@tests.mozilla.org.xpi!/manifest.json' (NS_ERROR_FAILURE)" {file: "resource://gre/modules/Extension.jsm" line: 824}]
[task 2023-03-23T21:48:50.319Z] 21:48:50     INFO - readJSON/</<@resource://gre/modules/Extension.jsm:824:20
[task 2023-03-23T21:48:50.319Z] 21:48:50     INFO - onStopRequest@resource://gre/modules/NetUtil.jsm:126:18
[task 2023-03-23T21:48:50.319Z] 21:48:50     INFO - 
[task 2023-03-23T21:48:50.320Z] 21:48:50     INFO - TEST-PASS | browser/base/content/test/webextensions/browser_extension_update_background.js | No unexamined telemetry events after test is finished - [] deepEqual [] - 
[task 2023-03-23T21:48:50.322Z] 21:48:50     INFO - Console message: 1679608129675	addons.xpi	WARN	Exception running bootstrap method startup on update_icon2@tests.mozilla.org: Error: Error while loading 'jar:file:///tmp/tmp2350e124.mozrunner/extensions/update_icon2@tests.mozilla.org.xpi!/manifest.json' (NS_ERROR_FAILURE)(resource://gre/modules/Extension.jsm:824:20) JS Stack trace: readJSON/</<@Extension.jsm:824:20
[task 2023-03-23T21:48:50.322Z] 21:48:50     INFO - onStopRequest@NetUtil.jsm:126:18
[task 2023-03-23T21:48:50.323Z] 21:48:50     INFO - GECKO(2598) | [Parent 2598, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2023-03-23T21:48:50.324Z] 21:48:50     INFO - GECKO(2598) | [Parent 2598, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2023-03-23T21:48:50.324Z] 21:48:50     INFO - GECKO(2598) | [Child 2704: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7ff87bc24000 == 1 [pid = 2704] [id = 3]
[task 2023-03-23T21:48:50.325Z] 21:48:50     INFO - GECKO(2598) | [Child 2704: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7ff880f73090) [pid = 2704] [serial = 10] [outer = 0]
[task 2023-03-23T21:48:50.326Z] 21:48:50     INFO - GECKO(2598) | [Child 2704: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7ff87bc24800) [pid = 2704] [serial = 11] [outer = 7ff880f73090]
[task 2023-03-23T21:48:50.327Z] 21:48:50     INFO - GECKO(2598) | [Parent 2598, Main Thread] WARNING: NS_ENSURE_TRUE(dsti2) failed: file /builds/worker/checkouts/gecko/dom/base/nsFocusManager.cpp:1914
[task 2023-03-23T21:48:50.328Z] 21:48:50     INFO - GECKO(2598) | [Child 2704: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7ff87bc1b000) [pid = 2704] [serial = 12] [outer = 7ff880f73090]
[task 2023-03-23T21:48:50.329Z] 21:48:50     INFO - GECKO(2598) | [Parent 2598, Main Thread] WARNING: 'aOwner->IsDiscarded()', file /builds/worker/workspace/obj-build/dist/include/mozilla/dom/SyncedContextInlines.h:95
[task 2023-03-23T21:48:50.417Z] 21:48:50     INFO - GECKO(2598) | MEMORY STAT | vsize 3468MB | residentFast 630MB | heapAllocated 317MB
[task 2023-03-23T21:48:50.419Z] 21:48:50     INFO - TEST-OK | browser/base/content/test/webextensions/browser_extension_update_background.js | took 10280ms
[task 2023-03-23T21:48:50.430Z] 21:48:50     INFO - GECKO(2598) | [Child 2760: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f9f5eb3b400 == 1 [pid = 2760] [id = 2]
[task 2023-03-23T21:48:50.432Z] 21:48:50     INFO - GECKO(2598) | [Child 2760: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f9f63d4f470) [pid = 2760] [serial = 5] [outer = 0]
[task 2023-03-23T21:48:50.433Z] 21:48:50     INFO - GECKO(2598) | [Child 2760: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f9f5eb3bc00) [pid = 2760] [serial = 6] [outer = 7f9f63d4f470]
[task 2023-03-23T21:48:50.489Z] 21:48:50     INFO - checking window state
[task 2023-03-23T21:48:50.559Z] 21:48:50     INFO - TEST-START | browser/base/content/test/webextensions/browser_extension_update_background_noprompt.js

First occurrence so far in this range of retriggers and backfills

Whiteboard: [retriggered]
Duplicate of this bug: 1406269

The severity field for this bug is set to S4. However, the following bug duplicate has higher severity:

:willdurand, could you consider increasing the severity of this bug to S3?

For more information, please visit auto_nag documentation.

Flags: needinfo?(wdurand)

S4 seems enough for now.

Flags: needinfo?(wdurand)
Attachment #9386866 - Attachment is obsolete: true

The Bugbug bot thinks this bug should belong to the 'Firefox::Tabbed Browser' component, and is moving the bug to that component. Please correct in case you think the bot is wrong.

Component: Untriaged → Tabbed Browser
Product: WebExtensions → Firefox
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: