Closed Bug 1878547 Opened 2 years ago Closed 2 years ago

Intermittent [tier 2] toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html | single tracking bug

Categories

(WebExtensions :: General, defect, P5)

Product:
WebExtensions
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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

[task 2024-02-04T10:34:43.940Z] 10:34:43     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html
[task 2024-02-04T10:34:44.430Z] 10:34:44     INFO - GECKO(15534) | [CodeCoverage] Setting handlers for process 21762.
[task 2024-02-04T10:34:44.594Z] 10:34:44     INFO - GECKO(15534) | Console message: Warning: attempting to write 13395 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file.
[task 2024-02-04T10:34:53.637Z] 10:34:53     INFO - GECKO(15534) | Console message: [JavaScript Error: "Timeout while waiting for extension child to shutdown: "Generated extension" (ID: a1eb8445daeccc4e613add583444dfd02c906617@temporary-addon, moz-extension://6ff49d15-2a87-4204-bd9c-ded1827e20ce/)" {file: "resource://gre/modules/Extension.sys.mjs" line: 3813}]
[task 2024-02-04T10:34:53.639Z] 10:34:53     INFO - GECKO(15534) | shutdown@resource://gre/modules/Extension.sys.mjs:3813:10
[task 2024-02-04T10:34:53.640Z] 10:34:53     INFO - GECKO(15534) | async*shutdown@resource://gre/modules/Extension.sys.mjs:2726:39
[task 2024-02-04T10:34:53.640Z] 10:34:53     INFO - GECKO(15534) | callBootstrapMethod@resource://gre/modules/addons/XPIProvider.sys.mjs:1850:33
[task 2024-02-04T10:34:53.640Z] 10:34:53     INFO - GECKO(15534) | _shutdown@resource://gre/modules/addons/XPIProvider.sys.mjs:1998:17
[task 2024-02-04T10:34:53.641Z] 10:34:53     INFO - GECKO(15534) | async*shutdown@resource://gre/modules/addons/XPIProvider.sys.mjs:1991:33
[task 2024-02-04T10:34:53.641Z] 10:34:53     INFO - GECKO(15534) | _uninstall@resource://gre/modules/addons/XPIProvider.sys.mjs:2076:18
[task 2024-02-04T10:34:53.642Z] 10:34:53     INFO - GECKO(15534) | uninstall@resource://gre/modules/addons/XPIProvider.sys.mjs:2071:17
[task 2024-02-04T10:34:53.642Z] 10:34:53     INFO - GECKO(15534) | uninstallAddon@resource://gre/modules/addons/XPIInstall.sys.mjs:4830:25
[task 2024-02-04T10:34:53.643Z] 10:34:53     INFO - GECKO(15534) | uninstall@resource://gre/modules/addons/XPIDatabase.sys.mjs:1441:34
[task 2024-02-04T10:34:53.643Z] 10:34:53     INFO - GECKO(15534) | shutdown@resource://testing-common/ExtensionTestCommon.sys.mjs:170:16
[task 2024-02-04T10:34:53.648Z] 10:34:53     INFO - GECKO(15534) | receiveMessage@resource://testing-common/SpecialPowersParent.sys.mjs:1344:28
[task 2024-02-04T10:34:53.648Z] 10:34:53     INFO - GECKO(15534) | JSActor query*unload@resource://testing-common/SpecialPowersChild.sys.mjs:2014:19
[task 2024-02-04T10:34:53.648Z] 10:34:53     INFO - GECKO(15534) | doApply@resource://testing-common/WrapPrivileged.sys.mjs:117:18
[task 2024-02-04T10:34:53.648Z] 10:34:53     INFO - GECKO(15534) | apply/<@resource://testing-common/WrapPrivileged.sys.mjs:252:30
[task 2024-02-04T10:34:53.648Z] 10:34:53     INFO - GECKO(15534) | wrapExceptions@resource://testing-common/WrapPrivileged.sys.mjs:205:12
[task 2024-02-04T10:34:53.648Z] 10:34:53     INFO - GECKO(15534) | apply@resource://testing-common/WrapPrivileged.sys.mjs:236:12
[task 2024-02-04T10:34:53.648Z] 10:34:53     INFO - GECKO(15534) | test_removeCSS_with_invalid_tabId@http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_scripting_removeCSS.html&closeWhenDone=1&showTestReport=false&expected=pass:62:19
[task 2024-02-04T10:34:53.649Z] 10:34:53     INFO - GECKO(15534) | async*add_task/nextTick/<@http://mochi.test:8888/tests/SimpleTest/SimpleTest.js:2189:34
[task 2024-02-04T10:34:53.649Z] 10:34:53     INFO - GECKO(15534) | async*nextTick@http://mochi.test:8888/tests/SimpleTest/SimpleTest.js:2233:11
[task 2024-02-04T10:34:53.649Z] 10:34:53     INFO - GECKO(15534) | setTimeout handler*SimpleTest_setTimeoutShim@http://mochi.test:8888/tests/SimpleTest/SimpleTest.js:922:41
[task 2024-02-04T10:34:53.649Z] 10:34:53     INFO - GECKO(15534) | add_task@http://mochi.test:8888/tests/SimpleTest/SimpleTest.js:2137:17
[task 2024-02-04T10:34:53.649Z] 10:34:53     INFO - GECKO(15534) | @http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html?currentTestURL=toolkit%2Fcomponents%2Fextensions%2Ftest%2Fmochitest%2Ftest_ext_scripting_removeCSS.html&closeWhenDone=1&showTestReport=false&expected=pass:36:9
[task 2024-02-04T10:34:53.649Z] 10:34:53     INFO - GECKO(15534) | Console message: Warning: attempting to write 13297 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file.
[task 2024-02-04T10:39:58.146Z] 10:39:58     INFO - TEST-INFO | started process screentopng
[task 2024-02-04T10:39:58.263Z] 10:39:58     INFO - TEST-INFO | screentopng: exit 0
[task 2024-02-04T10:39:58.263Z] 10:39:58     INFO - Buffered messages logged at 10:34:44
[task 2024-02-04T10:39:58.263Z] 10:39:58     INFO - add_task | Entering setup
[task 2024-02-04T10:39:58.263Z] 10:39:58     INFO - add_task | Leaving setup
[task 2024-02-04T10:39:58.263Z] 10:39:58     INFO - add_task | Entering test_removeCSS_with_invalid_tabId
[task 2024-02-04T10:39:58.264Z] 10:39:58     INFO - Extension loaded
[task 2024-02-04T10:39:58.264Z] 10:39:58     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html | Promise rejected, expecting rejection to match '"Invalid tab ID: 123456789"', got 'Error: Invalid tab ID: 123456789' 
[task 2024-02-04T10:39:58.265Z] 10:39:58     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html | remove-css 
[task 2024-02-04T10:39:58.265Z] 10:39:58     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html | test result correct 
[task 2024-02-04T10:39:58.266Z] 10:39:58     INFO - Buffered messages finished
[task 2024-02-04T10:39:58.266Z] 10:39:58     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html | Test timed out. - 
[task 2024-02-04T10:39:59.151Z] 10:39:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-02-04T10:39:59.152Z] 10:39:59     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html | Extension not fully unloaded at test shutdown 
[task 2024-02-04T10:39:59.152Z] 10:39:59     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:426:16
[task 2024-02-04T10:39:59.152Z] 10:39:59     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:135:18
[task 2024-02-04T10:39:59.152Z] 10:39:59     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1568:13
[task 2024-02-04T10:39:59.152Z] 10:39:59     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1582:3
[task 2024-02-04T10:39:59.152Z] 10:39:59     INFO -     @SimpleTest/SimpleTest.js:2265:16
[task 2024-02-04T10:39:59.152Z] 10:39:59     INFO -     async*@SimpleTest/SimpleTest.js:2262:17
[task 2024-02-04T10:39:59.160Z] 10:39:59     INFO - GECKO(15534) | MEMORY STAT | vsize 2745MB | residentFast 282MB | heapAllocated 8MB
[task 2024-02-04T10:39:59.201Z] 10:39:59     INFO - GECKO(15534) | JavaScript error: resource://testing-common/WrapPrivileged.sys.mjs, line 134: SecurityError: Permission denied to access property "windowGlobalChild" on cross-origin object
[task 2024-02-04T10:39:59.216Z] 10:39:59     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_scripting_removeCSS.html | took 315277ms
[task 2024-02-04T10:39:59.273Z] 10:39:59     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_sendmessage_doublereply.html
[task 2024-02-04T10:45:28.152Z] 10:45:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-02-04T10:45:28.153Z] 10:45:28     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_sendmessage_doublereply.html | Test timed out. - 
[task 2024-02-04T10:45:58.155Z] 10:45:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-02-04T10:45:58.155Z] 10:45:58     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_sendmessage_doublereply.html | Test timed out. - 
[task 2024-02-04T10:46:28.155Z] 10:46:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-02-04T10:46:28.157Z] 10:46:28     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_sendmessage_doublereply.html | Test timed out. - 
[task 2024-02-04T10:46:28.157Z] 10:46:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-02-04T10:46:28.158Z] 10:46:28     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2024-02-04T10:46:28.158Z] 10:46:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-02-04T10:46:28.159Z] 10:46:28     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 35 remaining tests. - 
[task 2024-02-04T10:52:38.170Z] 10:52:38     INFO - TEST-UNEXPECTED-TIMEOUT | toolkit/components/extensions/test/mochitest/test_ext_sendmessage_doublereply.html | application timed out after 370 seconds with no output
[task 2024-02-04T10:52:38.170Z] 10:52:38     INFO - TEST-INFO took 758732ms
[task 2024-02-04T10:52:38.170Z] 10:52:38     INFO - Buffered messages finished
[task 2024-02-04T10:52:38.171Z] 10:52:38  WARNING - Force-terminating active process(es).

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.