Closed Bug 1594803 Opened 6 years ago Closed 6 years ago

Intermittent devtools/client/performance/test/browser_perf-console-record-06.js | Test timed out - | Found a tab after previous test timed out: http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#5130066724527837

Categories

(DevTools :: Performance Tools (Profiler/Timeline), defect, P5)

Product:
DevTools
Component:
Performance Tools (Profiler/Timeline)
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=275116219&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/eaP5JLIRQWm4i0tasWVtfg/runs/0/artifacts/public/logs/live_backing.log

[task 2019-11-07T16:34:18.055Z] 16:34:18 INFO - TEST-START | devtools/client/performance/test/browser_perf-console-record-06.js
[task 2019-11-07T16:34:18.055Z] 16:34:18 INFO - GECKO(7064) | Adding tab with url: http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#5130066724527837.
[task 2019-11-07T16:34:18.365Z] 16:34:18 INFO - GECKO(7064) | Initializing a webconsole panel.
[task 2019-11-07T16:34:18.694Z] 16:34:18 INFO - GECKO(7064) | Initializing a performance panel.
[task 2019-11-07T16:34:18.833Z] 16:34:18 INFO - GECKO(7064) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-started"].
[task 2019-11-07T16:34:18.833Z] 16:34:18 INFO - GECKO(7064) | Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView] for 1 time(s) with arguments: [{}].
[task 2019-11-07T16:34:18.833Z] 16:34:18 INFO - GECKO(7064) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 1 time(s) with arguments: [100].
[task 2019-11-07T16:34:18.833Z] 16:34:18 INFO - GECKO(7064) | Waiting for event: 'recording-started' on [Front for performance/server0.conn10.child1/performanceActor10] for 1 time(s).
[task 2019-11-07T16:34:18.863Z] 16:34:18 INFO - GECKO(7064) | Event: 'Performance:UI:StateChanged' on [object PerformanceView] received.
[task 2019-11-07T16:34:18.882Z] 16:34:18 INFO - GECKO(7064) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2019-11-07T16:34:18.882Z] 16:34:18 INFO - GECKO(7064) | Event: 'recording-started' on [Front for performance/server0.conn10.child1/performanceActor10] received.
[task 2019-11-07T16:34:19.083Z] 16:34:19 INFO - GECKO(7064) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] received.
[task 2019-11-07T16:34:19.083Z] 16:34:19 INFO - GECKO(7064) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 3 time(s) with arguments: [100].
[task 2019-11-07T16:34:19.288Z] 16:34:19 INFO - GECKO(7064) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] needs to be fired 2 more time(s).
[task 2019-11-07T16:34:19.502Z] 16:34:19 INFO - GECKO(7064) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] needs to be fired 1 more time(s).
[task 2019-11-07T16:34:19.723Z] 16:34:19 INFO - GECKO(7064) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] received.
[task 2019-11-07T16:34:19.723Z] 16:34:19 INFO - GECKO(7064) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-started"].
[task 2019-11-07T16:34:19.723Z] 16:34:19 INFO - GECKO(7064) | Waiting for event: 'recording-started' on [Front for performance/server0.conn10.child1/performanceActor10] for 1 time(s).
[task 2019-11-07T16:36:33.063Z] 16:36:33 INFO - TEST-INFO | started process screenshot
[task 2019-11-07T16:36:33.143Z] 16:36:33 INFO - TEST-INFO | screenshot: exit 0
[task 2019-11-07T16:36:33.143Z] 16:36:33 INFO - Buffered messages logged at 16:34:18
[task 2019-11-07T16:36:33.143Z] 16:36:33 INFO - Entering test bound
[task 2019-11-07T16:36:33.144Z] 16:36:33 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2019-11-07T16:36:33.144Z] 16:36:33 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2019-11-07T16:36:33.145Z] 16:36:33 INFO - Buffered messages logged at 16:34:19
[task 2019-11-07T16:36:33.145Z] 16:36:33 INFO - TEST-PASS | devtools/client/performance/test/browser_perf-console-record-06.js | A recording found in the performance panel. -
[task 2019-11-07T16:36:33.145Z] 16:36:33 INFO - TEST-PASS | devtools/client/performance/test/browser_perf-console-record-06.js | The first console recording should be selected. -
[task 2019-11-07T16:36:33.146Z] 16:36:33 INFO - Buffered messages logged at 16:35:03
[task 2019-11-07T16:36:33.146Z] 16:36:33 INFO - Longer timeout required, waiting longer... Remaining timeouts: 2
[task 2019-11-07T16:36:33.146Z] 16:36:33 INFO - Buffered messages logged at 16:35:48
[task 2019-11-07T16:36:33.146Z] 16:36:33 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2019-11-07T16:36:33.146Z] 16:36:33 INFO - Buffered messages finished
[task 2019-11-07T16:36:33.146Z] 16:36:33 INFO - TEST-UNEXPECTED-FAIL | devtools/client/performance/test/browser_perf-console-record-06.js | Test timed out -
[task 2019-11-07T16:36:33.146Z] 16:36:33 INFO - finish() was called, cleaning up...
[task 2019-11-07T16:36:33.369Z] 16:36:33 INFO - GECKO(7064) | MEMORY STAT | vsize 2104424MB | vsizeMaxContiguous 65361109MB | residentFast 397MB | heapAllocated 194MB
[task 2019-11-07T16:36:33.369Z] 16:36:33 INFO - TEST-OK | devtools/client/performance/test/browser_perf-console-record-06.js | took 135315ms
[task 2019-11-07T16:36:33.369Z] 16:36:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-11-07T16:36:33.369Z] 16:36:33 INFO - TEST-UNEXPECTED-FAIL | devtools/client/performance/test/browser_perf-console-record-06.js | Found a tab after previous test timed out: http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#5130066724527837 -
[task 2019-11-07T16:36:33.427Z] 16:36:33 INFO - checking window state

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.