Closed Bug 1768323 Opened 3 years ago Closed 3 years ago

Intermittent toolkit/components/extensions/test/mochitest/test_ext_script_filenames.html | Test timed out. | Test left extra windows or tabs: {"extraWindows":{},"extraTabs":{"0":"http://mochi.test:8888/"}}

Categories

(WebExtensions :: General, defect, P5)

Product:
WebExtensions
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[task 2022-05-07T12:36:18.167Z] 12:36:18     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_script_filenames.html
[task 2022-05-07T12:36:18.214Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.215Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.236Z] 12:36:18     INFO - GECKO(1984) | [Parent 1984, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3019
[task 2022-05-07T12:36:18.278Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.405Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.433Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.442Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-05-07T12:36:18.454Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-05-07T12:36:18.456Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.462Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.464Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.465Z] 12:36:18     INFO - GECKO(1984) | Console message: Warning: attempting to write 9852 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. This preference will not be sent to any content processes.
[task 2022-05-07T12:36:18.471Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:510
[task 2022-05-07T12:36:18.482Z] 12:36:18     INFO - GECKO(1984) | [Child 4938, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4571
[task 2022-05-07T12:36:18.676Z] 12:36:18     INFO - GECKO(1984) | Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://mochi.test:8888/" line: 0}]
[task 2022-05-07T12:36:18.868Z] 12:36:18     INFO - GECKO(1984) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpi_vuozt2.mozrunner/runtests_leaks_tab_pid5049.log
[task 2022-05-07T12:36:18.871Z] 12:36:18     INFO - GECKO(1984) | [5049, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-07T12:36:18.872Z] 12:36:18     INFO - GECKO(1984) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpi_vuozt2.mozrunner/runtests_leaks_tab_pid5052.log
[task 2022-05-07T12:36:18.873Z] 12:36:18     INFO - GECKO(1984) | [5052, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-07T12:36:18.909Z] 12:36:18     INFO - GECKO(1984) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpi_vuozt2.mozrunner/runtests_leaks_tab_pid5055.log
[task 2022-05-07T12:36:18.910Z] 12:36:18     INFO - GECKO(1984) | [5055, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-07T12:36:18.930Z] 12:36:18     INFO - GECKO(1984) | [Child 5049, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:693
[task 2022-05-07T12:36:18.934Z] 12:36:18     INFO - GECKO(1984) | [Child 5052, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:693
[task 2022-05-07T12:36:18.964Z] 12:36:18     INFO - GECKO(1984) | [Child 5055, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:693
[task 2022-05-07T12:39:51.887Z] 12:39:51     INFO - GECKO(1984) | [2022-05-07T12:39:51Z WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2022-05-07T12:39:51.890Z] 12:39:51     INFO - GECKO(1984) | Console message: [JavaScript Error: "Invalid ETag value "undefined"" {file: "resource://services-settings/SyncHistory.jsm" line: 50}]
[task 2022-05-07T12:39:51.890Z] 12:39:51     INFO - GECKO(1984) | store@resource://services-settings/SyncHistory.jsm:50:13
[task 2022-05-07T12:41:32.434Z] 12:41:32     INFO - TEST-INFO | started process screentopng
[task 2022-05-07T12:41:32.580Z] 12:41:32     INFO - TEST-INFO | screentopng: exit 0
[task 2022-05-07T12:41:32.582Z] 12:41:32     INFO - Buffered messages logged at 12:36:18
[task 2022-05-07T12:41:32.583Z] 12:41:32     INFO - add_task | Entering test test_tabs_executeScript
[task 2022-05-07T12:41:32.583Z] 12:41:32     INFO - Extension loaded
[task 2022-05-07T12:41:32.584Z] 12:41:32     INFO - Buffered messages finished
[task 2022-05-07T12:41:32.585Z] 12:41:32     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_script_filenames.html | Test timed out. - 
[task 2022-05-07T12:41:33.453Z] 12:41:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-07T12:41:33.455Z] 12:41:33     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_script_filenames.html | Test left extra windows or tabs: {"extraWindows":{},"extraTabs":{"0":"http://mochi.test:8888/"}}
[task 2022-05-07T12:41:33.456Z] 12:41:33     INFO -  
[task 2022-05-07T12:41:33.456Z] 12:41:33     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-05-07T12:41:33.457Z] 12:41:33     INFO -     @toolkit/components/extensions/test/mochitest/head.js:33:9
[task 2022-05-07T12:41:33.466Z] 12:41:33     INFO - GECKO(1984) | MEMORY STAT | vsize 2534MB | residentFast 148MB | heapAllocated 8MB
[task 2022-05-07T12:41:33.480Z] 12:41:33     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_script_filenames.html | took 315312ms
[task 2022-05-07T12:41:36.486Z] 12:41:36     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2022-05-07T12:41:36.511Z] 12:41:36     INFO - GECKO(1984) | JavaScript error: resource://specialpowers/WrapPrivileged.jsm, line 135: SecurityError: Permission denied to access property "windowGlobalChild" on cross-origin object
[task 2022-05-07T12:41:36.544Z] 12:41:36     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_scripting_contentScripts.html

Hi Edgar! Can you please take a look at this? Could this be regressed by Bug 1766860?

Flags: needinfo?(echen)

No, I don't think this is a regression of bug 1766860. bug 1766860 only affect how does clipboard API be exposed to the web. And toolkit/components/extensions/test/mochitest/test_ext_script_filenames.html is a test for extension and don't even use any clipboard API.

Flags: needinfo?(echen)

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

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=388448178&repo=autoland

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=393572309&repo=autoland

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=396792523&repo=autoland

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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