Closed Bug 1637111 Opened 6 years ago Closed 6 years ago

Intermittent devtools/server/tests/browser/browser_perf-allocation-data.js | Test timed out - | and other tests in the path timing out

Categories

(DevTools :: General, defect, P5)

Product:
DevTools
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[task 2020-05-11T22:41:58.906Z] 22:41:58     INFO - TEST-START | devtools/server/tests/browser/browser_perf-allocation-data.js
[task 2020-05-11T22:43:28.983Z] 22:43:28     INFO - TEST-INFO | started process screenshot
[task 2020-05-11T22:43:29.204Z] 22:43:29     INFO - TEST-INFO | screenshot: exit 0
[task 2020-05-11T22:43:29.204Z] 22:43:29     INFO - Buffered messages logged at 22:41:58
[task 2020-05-11T22:43:29.205Z] 22:43:29     INFO - Entering test bound 
[task 2020-05-11T22:43:29.205Z] 22:43:29     INFO - Adding a new tab with URL: http://test1.example.org/browser/devtools/server/tests/browser/doc_allocations.html
[task 2020-05-11T22:43:29.206Z] 22:43:29     INFO - Buffered messages logged at 22:41:59
[task 2020-05-11T22:43:29.206Z] 22:43:29     INFO - Tab added a URL http://test1.example.org/browser/devtools/server/tests/browser/doc_allocations.html loaded
[task 2020-05-11T22:43:29.206Z] 22:43:29     INFO - Attaching to the active tab.
[task 2020-05-11T22:43:29.207Z] 22:43:29     INFO - Buffered messages finished
[task 2020-05-11T22:43:29.207Z] 22:43:29     INFO - TEST-UNEXPECTED-FAIL | devtools/server/tests/browser/browser_perf-allocation-data.js | Test timed out - 
[task 2020-05-11T22:43:29.207Z] 22:43:29     INFO - Removing tab.
...
[task 2020-05-11T22:43:58.533Z] 22:43:58     INFO - TEST-START | devtools/server/tests/browser/browser_webextension_inspected_window.js
[task 2020-05-11T22:46:58.617Z] 22:46:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-11T22:46:58.617Z] 22:46:58     INFO - Buffered messages logged at 22:43:58
[task 2020-05-11T22:46:58.617Z] 22:46:58     INFO - Entering test bound test_successfull_inspectedWindowEval_result
[task 2020-05-11T22:46:58.617Z] 22:46:58     INFO - Extension loaded
[task 2020-05-11T22:46:58.617Z] 22:46:58     INFO - Adding a new tab with URL: http://test1.example.org/browser/devtools/server/tests/browser/
[task 2020-05-11T22:46:58.617Z] 22:46:58     INFO - Buffered messages logged at 22:43:59
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - Tab added a URL http://test1.example.org/browser/devtools/server/tests/browser/ loaded
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - Attaching to the active tab.
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - TEST-PASS | devtools/server/tests/browser/browser_webextension_inspected_window.js | Got a result from inspectedWindow eval - 
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - TEST-PASS | devtools/server/tests/browser/browser_webextension_inspected_window.js | Got the expected window.location.href property value - 
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - TEST-PASS | devtools/server/tests/browser/browser_webextension_inspected_window.js | Got the expected window.location.protocol property value - 
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - Buffered messages logged at 22:44:07
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - Console message: [JavaScript Error: "Timeout while waiting for extension child to shutdown: "Generated extension" (ID: {fb0b8614-aadf-4d76-926d-ac6b3c9ac31f}, moz-extension://82341e10-2a63-4bcc-801d-81c76c7a6150/)" {file: "resource://gre/modules/Extension.jsm" line: 2636}]
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - shutdown@resource://gre/modules/Extension.jsm:2636:10
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - async*receiveMessage@resource://specialpowers/SpecialPowersParent.jsm:1089:26
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - JSActor query*unload@resource://specialpowers/SpecialPowersChild.jsm:2060:19
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - teardown@chrome://mochitests/content/browser/devtools/server/tests/browser/browser_webextension_inspected_window.js:50:19
[task 2020-05-11T22:46:58.618Z] 22:46:58     INFO - async*test_successfull_inspectedWindowEval_result@chrome://mochitests/content/browser/devtools/server/tests/browser/browser_webextension_inspected_window.js:122:9
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1064:34
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1104:11
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:927:14
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:918:23
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - 
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - Buffered messages logged at 22:44:36
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - Console message: [JavaScript Error: "Unknown Collection "main/messaging-experiments"" {file: "resource://services-settings/RemoteSettingsClient.jsm" line: 145}]
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - UnknownCollectionError@resource://services-settings/RemoteSettingsClient.jsm:145:5
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - sync@resource://services-settings/RemoteSettingsClient.jsm:407:13
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - async*get@resource://services-settings/RemoteSettingsClient.jsm:342:22
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - async*updateRecipes@resource://messaging-system/lib/RemoteSettingsExperimentLoader.jsm:141:49
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - setTimer/<@resource://messaging-system/lib/RemoteSettingsExperimentLoader.jsm:188:18
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - TM_notify/</<@resource://gre/modules/UpdateTimerManager.jsm:257:36
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - ChromeUtils::IdleDispatch handler*TM_notify/<@resource://gre/modules/UpdateTimerManager.jsm:255:25
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - TM_notify@resource://gre/modules/UpdateTimerManager.jsm:290:7
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - 
[task 2020-05-11T22:46:58.619Z] 22:46:58     INFO - Buffered messages logged at 22:45:11
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - Console message: [JavaScript Error: "getScreenshot(http://test1.example.org/browser/devtools/server/tests/browser/inspector-traversal-data.html) failed: TypeError: NetworkError when attempting to fetch resource." {file: "resource://activity-stream/lib/Screenshots.jsm" line: 79}]
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:79:10
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:132:37
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - _fetchScreenshot@resource://activity-stream/lib/TopSitesFeed.jsm:527:23
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - _fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:515:16
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:408:16
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:431:30
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - onAction@resource://activity-stream/lib/TopSitesFeed.jsm:771:14
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:63:17
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:39:54
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:27:24
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - notify@resource://gre/modules/Timer.jsm:62:17
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - 
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - Buffered messages finished
[task 2020-05-11T22:46:58.620Z] 22:46:58     INFO - TEST-UNEXPECTED-FAIL | devtools/server/tests/browser/browser_webextension_inspected_window.js | Test timed out - 
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - TEST-PASS | devtools/server/tests/browser/browser_webextension_inspected_window.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - TEST-UNEXPECTED-FAIL | devtools/server/tests/browser/browser_webextension_inspected_window.js | Extension not fully unloaded at test shutdown - 
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - Stack trace:
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - chrome://mochikit/content/browser-test.js:test_ok:1299
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:120
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - chrome://mochikit/content/browser-test.js:nextTest:550
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1190
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - setTimeout handler*chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest_setTimeoutShim:788
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1178
[task 2020-05-11T22:46:58.659Z] 22:46:58     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1137
[task 2020-05-11T22:46:58.660Z] 22:46:58     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:927
[task 2020-05-11T22:46:58.660Z] 22:46:58     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-05-11T22:46:58.724Z] 22:46:58     INFO - GECKO(732) | MEMORY STAT | vsize 19406179MB | vsizeMaxContiguous 65061698MB | residentFast 1631MB
[task 2020-05-11T22:46:58.724Z] 22:46:58     INFO - TEST-OK | devtools/server/tests/browser/browser_webextension_inspected_window.js | took 180192ms

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

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