Closed Bug 1724806 Opened 4 years ago Closed 3 years ago

Intermittent toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html | Test timed out. - | Extension left running at test shutdown

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1781605

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=347882315&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/SUQUA-3XRsi68FPB3pg86A/runs/1/artifacts/public/logs/live_backing.log


[task 2021-08-09T21:19:18.327Z] 21:19:18     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html
[task 2021-08-09T21:19:18.431Z] 21:19:18     INFO - GECKO(1891) | [Child 3895, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-09T21:19:18.459Z] 21:19:18     INFO - GECKO(1891) | [Child 3895, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-09T21:19:18.499Z] 21:19:18     INFO - GECKO(1891) | [Parent 1891, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2978
[task 2021-08-09T21:19:18.948Z] 21:19:18     INFO - GECKO(1891) | Console message: Warning: attempting to write 6193 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 2021-08-09T21:19:18.987Z] 21:19:18     INFO - GECKO(1891) | [Child 1986, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2857
[task 2021-08-09T21:19:19.510Z] 21:19:19     INFO - GECKO(1891) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp499g4kel.mozrunner/runtests_leaks_tab_pid3999.log
[task 2021-08-09T21:19:19.512Z] 21:19:19     INFO - GECKO(1891) | [3999, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-09T21:19:19.543Z] 21:19:19     INFO - GECKO(1891) | [Child 3999, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-09T21:21:28.500Z] 21:21:28     INFO - GECKO(1891) | Console message: [JavaScript Error: "Failed to fetch data from Contile server: NetworkError when attempting to fetch resource." {file: "resource://activity-stream/lib/TopSitesFeed.jsm" line: 200}]
[task 2021-08-09T21:21:28.500Z] 21:21:28     INFO - GECKO(1891) | _fetchSites@resource://activity-stream/lib/TopSitesFeed.jsm:200:10
[task 2021-08-09T21:24:32.462Z] 21:24:32     INFO - TEST-INFO | started process screentopng
[task 2021-08-09T21:24:32.607Z] 21:24:32     INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-09T21:24:32.608Z] 21:24:32     INFO - Buffered messages logged at 21:19:18
[task 2021-08-09T21:24:32.609Z] 21:24:32     INFO - add_task | Entering test test_listener_proxies
[task 2021-08-09T21:24:32.609Z] 21:24:32     INFO - Extension loaded
[task 2021-08-09T21:24:32.609Z] 21:24:32     INFO - Buffered messages finished
[task 2021-08-09T21:24:32.609Z] 21:24:32     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html | Test timed out. - 
[task 2021-08-09T21:24:33.690Z] 21:24:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-09T21:24:33.692Z] 21:24:33     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html | Extension left running at test shutdown 
[task 2021-08-09T21:24:33.692Z] 21:24:33     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-08-09T21:24:33.692Z] 21:24:33     INFO -     ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2021-08-09T21:24:33.692Z] 21:24:33     INFO -     executeCleanupFunction@SimpleTest/SimpleTest.js:1487:13
[task 2021-08-09T21:24:33.693Z] 21:24:33     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1501:3
[task 2021-08-09T21:24:33.693Z] 21:24:33     INFO -     @SimpleTest/SimpleTest.js:2141:16
[task 2021-08-09T21:24:33.717Z] 21:24:33     INFO - GECKO(1891) | [Child 1986, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | Console message: [JavaScript Error: "sendRemoveListener on closed conduit 79f3b3822b89614836af8f7f2e5f015f77ed200b@temporary-addon.274877907574" {file: "resource://gre/modules/ConduitsChild.jsm" line: 108}]
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | _send@resource://gre/modules/ConduitsChild.jsm:108:13
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:663:34
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:891:40
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | getAPI/register/<.storage.onChanged@chrome://extensions/content/child/ext-storage.js:332:22
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionCommon.jsm:2534:7
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | revoke@resource://gre/modules/ExtensionCommon.jsm:2556:12
[task 2021-08-09T21:24:33.790Z] 21:24:33     INFO - GECKO(1891) | close@resource://gre/modules/ExtensionCommon.jsm:2561:10
[task 2021-08-09T21:24:33.791Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionCommon.jsm:922:11
[task 2021-08-09T21:24:33.800Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:261:11
[task 2021-08-09T21:24:33.800Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:298:11
[task 2021-08-09T21:24:33.801Z] 21:24:33     INFO - GECKO(1891) | destroyExtensionContext@resource://gre/modules/ExtensionPageChild.jsm:482:15
[task 2021-08-09T21:24:33.801Z] 21:24:33     INFO - GECKO(1891) | observe@resource://gre/modules/ExtensionPageChild.jsm:395:12
[task 2021-08-09T21:24:33.801Z] 21:24:33     INFO - GECKO(1891) | Console message: [JavaScript Error: "sendRemoveListener on closed conduit 79f3b3822b89614836af8f7f2e5f015f77ed200b@temporary-addon.274877907574" {file: "resource://gre/modules/ConduitsChild.jsm" line: 108}]
[task 2021-08-09T21:24:33.802Z] 21:24:33     INFO - GECKO(1891) | _send@resource://gre/modules/ConduitsChild.jsm:108:13
[task 2021-08-09T21:24:33.805Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:663:34
[task 2021-08-09T21:24:33.805Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:891:40
[task 2021-08-09T21:24:33.805Z] 21:24:33     INFO - GECKO(1891) | getAPI/register/<.storage.onChanged@chrome://extensions/content/child/ext-storage.js:332:22
[task 2021-08-09T21:24:33.806Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionCommon.jsm:2534:7
[task 2021-08-09T21:24:33.807Z] 21:24:33     INFO - GECKO(1891) | revoke@resource://gre/modules/ExtensionCommon.jsm:2556:12
[task 2021-08-09T21:24:33.808Z] 21:24:33     INFO - GECKO(1891) | close@resource://gre/modules/ExtensionCommon.jsm:2561:10
[task 2021-08-09T21:24:33.809Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionCommon.jsm:922:11
[task 2021-08-09T21:24:33.810Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:261:11
[task 2021-08-09T21:24:33.812Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:298:11
[task 2021-08-09T21:24:33.813Z] 21:24:33     INFO - GECKO(1891) | destroyExtensionContext@resource://gre/modules/ExtensionPageChild.jsm:482:15
[task 2021-08-09T21:24:33.814Z] 21:24:33     INFO - GECKO(1891) | observe@resource://gre/modules/ExtensionPageChild.jsm:395:12
[task 2021-08-09T21:24:33.814Z] 21:24:33     INFO - GECKO(1891) | Console message: [JavaScript Error: "sendRemoveListener on closed conduit 79f3b3822b89614836af8f7f2e5f015f77ed200b@temporary-addon.274877907574" {file: "resource://gre/modules/ConduitsChild.jsm" line: 108}]
[task 2021-08-09T21:24:33.815Z] 21:24:33     INFO - GECKO(1891) | _send@resource://gre/modules/ConduitsChild.jsm:108:13
[task 2021-08-09T21:24:33.815Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:663:34
[task 2021-08-09T21:24:33.816Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:891:40
[task 2021-08-09T21:24:33.817Z] 21:24:33     INFO - GECKO(1891) | getAPI/register/<.storage.onChanged@chrome://extensions/content/child/ext-storage.js:332:22
[task 2021-08-09T21:24:33.817Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionCommon.jsm:2534:7
[task 2021-08-09T21:24:33.818Z] 21:24:33     INFO - GECKO(1891) | revoke@resource://gre/modules/ExtensionCommon.jsm:2556:12
[task 2021-08-09T21:24:33.819Z] 21:24:33     INFO - GECKO(1891) | close@resource://gre/modules/ExtensionCommon.jsm:2561:10
[task 2021-08-09T21:24:33.820Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionCommon.jsm:922:11
[task 2021-08-09T21:24:33.820Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:261:11
[task 2021-08-09T21:24:33.821Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:298:11
[task 2021-08-09T21:24:33.822Z] 21:24:33     INFO - GECKO(1891) | destroyExtensionContext@resource://gre/modules/ExtensionPageChild.jsm:482:15
[task 2021-08-09T21:24:33.823Z] 21:24:33     INFO - GECKO(1891) | observe@resource://gre/modules/ExtensionPageChild.jsm:395:12
[task 2021-08-09T21:24:33.824Z] 21:24:33     INFO - GECKO(1891) | Console message: [JavaScript Error: "sendRemoveListener on closed conduit 79f3b3822b89614836af8f7f2e5f015f77ed200b@temporary-addon.274877907574" {file: "resource://gre/modules/ConduitsChild.jsm" line: 108}]
[task 2021-08-09T21:24:33.824Z] 21:24:33     INFO - GECKO(1891) | _send@resource://gre/modules/ConduitsChild.jsm:108:13
[task 2021-08-09T21:24:33.825Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:663:34
[task 2021-08-09T21:24:33.826Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionChild.jsm:891:40
[task 2021-08-09T21:24:33.827Z] 21:24:33     INFO - GECKO(1891) | getAPI/register/<.storage.onChanged@chrome://extensions/content/child/ext-storage.js:332:22
[task 2021-08-09T21:24:33.828Z] 21:24:33     INFO - GECKO(1891) | removeListener@resource://gre/modules/ExtensionCommon.jsm:2534:7
[task 2021-08-09T21:24:33.828Z] 21:24:33     INFO - GECKO(1891) | revoke@resource://gre/modules/ExtensionCommon.jsm:2556:12
[task 2021-08-09T21:24:33.829Z] 21:24:33     INFO - GECKO(1891) | close@resource://gre/modules/ExtensionCommon.jsm:2561:10
[task 2021-08-09T21:24:33.830Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionCommon.jsm:922:11
[task 2021-08-09T21:24:33.831Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:261:11
[task 2021-08-09T21:24:33.832Z] 21:24:33     INFO - GECKO(1891) | unload@resource://gre/modules/ExtensionPageChild.jsm:298:11
[task 2021-08-09T21:24:33.832Z] 21:24:33     INFO - GECKO(1891) | destroyExtensionContext@resource://gre/modules/ExtensionPageChild.jsm:482:15
[task 2021-08-09T21:24:33.833Z] 21:24:33     INFO - GECKO(1891) | observe@resource://gre/modules/ExtensionPageChild.jsm:395:12
[task 2021-08-09T21:24:33.834Z] 21:24:33     INFO - GECKO(1891) | Console message: Warning: attempting to write 6095 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 2021-08-09T21:24:33.850Z] 21:24:33     INFO - GECKO(1891) | MEMORY STAT | vsize 2523MB | residentFast 145MB | heapAllocated 8MB
[task 2021-08-09T21:24:33.866Z] 21:24:33     INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html | took 315541ms
[task 2021-08-09T21:24:33.903Z] 21:24:33     INFO - GECKO(1891) | [Parent 1891, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-09T21:24:33.916Z] 21:24:33     INFO - GECKO(1891) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-09T21:24:34.018Z] 21:24:34     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_new_tab_processType.html
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.