Intermittent devtools/client/performance/test/browser_perf-details-07-bleed-events.js | Test timed out -
Categories
(DevTools :: Performance Tools (Profiler/Timeline), defect, P5)
Tracking
(Not tracked)
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, regression)
Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=261770090&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/OWZBXrMjRwG_JYwEPSVBnA/runs/0/artifacts/public/logs/live_backing.log
[task 2019-08-15T12:13:26.702Z] 12:13:26 INFO - TEST-START | devtools/client/performance/test/browser_perf-details-07-bleed-events.js
[task 2019-08-15T12:13:26.721Z] 12:13:26 INFO - GECKO(8660) | Adding tab with url: http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#1384792110722182.
[task 2019-08-15T12:13:26.741Z] 12:13:26 INFO - GECKO(8660) | ++DOCSHELL 000002533177C800 == 2 [pid = 2736] [id = {7872894e-9334-46b2-975d-2e1521a077cf}]
[task 2019-08-15T12:13:26.741Z] 12:13:26 INFO - GECKO(8660) | ++DOMWINDOW == 3 (0000025336A29020) [pid = 2736] [serial = 40] [outer = 0000000000000000]
[task 2019-08-15T12:13:26.743Z] 12:13:26 INFO - GECKO(8660) | ++DOMWINDOW == 4 (00000253317DB000) [pid = 2736] [serial = 41] [outer = 0000025336A29020]
[task 2019-08-15T12:13:26.768Z] 12:13:26 INFO - GECKO(8660) | [Child 2736, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-08-15T12:13:26.768Z] 12:13:26 INFO - GECKO(8660) | [Child 2736, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-08-15T12:13:26.793Z] 12:13:26 INFO - GECKO(8660) | [Parent 7864, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file z:/build/build/src/obj-firefox/dist/include\mozilla/RangeBoundary.h, line 79
[task 2019-08-15T12:13:26.793Z] 12:13:26 INFO - GECKO(8660) | [Parent 7864, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file z:/build/build/src/obj-firefox/dist/include\mozilla/RangeBoundary.h, line 79
[task 2019-08-15T12:13:27.058Z] 12:13:27 INFO - GECKO(8660) | ++DOMWINDOW == 5 (0000025337F81C00) [pid = 2736] [serial = 42] [outer = 0000025336A29020]
[task 2019-08-15T12:13:27.359Z] 12:13:27 INFO - GECKO(8660) | Initializing a performance panel.
[task 2019-08-15T12:13:27.478Z] 12:13:27 INFO - GECKO(8660) | ++DOCSHELL 0000025D20C3A000 == 7 [pid = 7864] [id = {d1c4ec89-528d-4d78-a21b-7b0a9518620e}]
[task 2019-08-15T12:13:27.478Z] 12:13:27 INFO - GECKO(8660) | ++DOMWINDOW == 25 (0000025D2253EF20) [pid = 7864] [serial = 197] [outer = 0000000000000000]
[task 2019-08-15T12:13:27.480Z] 12:13:27 INFO - GECKO(8660) | ++DOMWINDOW == 26 (0000025D218CAC00) [pid = 7864] [serial = 198] [outer = 0000025D2253EF20]
[task 2019-08-15T12:13:27.665Z] 12:13:27 INFO - GECKO(8660) | ++DOMWINDOW == 27 (0000025D22507C00) [pid = 7864] [serial = 199] [outer = 0000025D2253EF20]
[task 2019-08-15T12:13:28.600Z] 12:13:28 INFO - GECKO(8660) | ++DOCSHELL 0000025D20C3B000 == 8 [pid = 7864] [id = {f7ee3c60-0931-44e2-bbd0-f99c5aa36181}]
[task 2019-08-15T12:13:28.600Z] 12:13:28 INFO - GECKO(8660) | ++DOMWINDOW == 28 (0000025D2253F880) [pid = 7864] [serial = 200] [outer = 0000000000000000]
[task 2019-08-15T12:13:28.600Z] 12:13:28 INFO - GECKO(8660) | ++DOMWINDOW == 29 (0000025D2250DC00) [pid = 7864] [serial = 201] [outer = 0000025D2253F880]
[task 2019-08-15T12:13:28.984Z] 12:13:28 INFO - GECKO(8660) | [Parent 7864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805D0021: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 994
[task 2019-08-15T12:13:29.449Z] 12:13:29 INFO - GECKO(8660) | --DOCSHELL 000002533177A800 == 1 [pid = 2736] [id = {b0563e20-ee8b-415e-8666-a497749921de}] [url = http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#8126094943660273]
[task 2019-08-15T12:13:29.794Z] 12:13:29 INFO - GECKO(8660) | --DOMWINDOW == 4 (0000025336A295C0) [pid = 2736] [serial = 37] [outer = 0000000000000000] [url = http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#8126094943660273]
[task 2019-08-15T12:13:30.821Z] 12:13:30 INFO - GECKO(8660) | Waiting for event: 'Performance:BackendReadyRecordingStart' on [object PerformanceController] for 1 time(s).
[task 2019-08-15T12:13:30.821Z] 12:13:30 INFO - GECKO(8660) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-started"].
[task 2019-08-15T12:13:30.821Z] 12:13:30 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView] for 1 time(s) with arguments: [{}].
[task 2019-08-15T12:13:30.821Z] 12:13:30 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 1 time(s) with arguments: [100].
[task 2019-08-15T12:13:31.084Z] 12:13:31 INFO - GECKO(8660) | Event: 'Performance:UI:StateChanged' on [object PerformanceView] received.
[task 2019-08-15T12:13:31.094Z] 12:13:31 INFO - GECKO(8660) | ++DOCSHELL 0000025D20C3F000 == 9 [pid = 7864] [id = {e1ff93e7-e2d2-4426-82a5-5e91cc57bbb5}]
[task 2019-08-15T12:13:31.094Z] 12:13:31 INFO - GECKO(8660) | ++DOMWINDOW == 30 (0000025D28335E20) [pid = 7864] [serial = 202] [outer = 0000000000000000]
[task 2019-08-15T12:13:31.094Z] 12:13:31 INFO - GECKO(8660) | ++DOMWINDOW == 31 (0000025D23246400) [pid = 7864] [serial = 203] [outer = 0000025D28335E20]
[task 2019-08-15T12:13:31.287Z] 12:13:31 INFO - GECKO(8660) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2019-08-15T12:13:31.287Z] 12:13:31 INFO - GECKO(8660) | Event: 'Performance:BackendReadyRecordingStart' on [object PerformanceController] received.
[task 2019-08-15T12:13:31.398Z] 12:13:31 INFO - GECKO(8660) | [Parent 7864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805D0021: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 994
[task 2019-08-15T12:13:31.945Z] 12:13:31 INFO - GECKO(8660) | ++DOCSHELL 0000025D20C43000 == 10 [pid = 7864] [id = {305698cd-e005-46ee-9468-19ece8efb448}]
[task 2019-08-15T12:13:31.945Z] 12:13:31 INFO - GECKO(8660) | ++DOMWINDOW == 32 (0000025D2D5183E0) [pid = 7864] [serial = 204] [outer = 0000000000000000]
[task 2019-08-15T12:13:31.945Z] 12:13:31 INFO - GECKO(8660) | ++DOMWINDOW == 33 (0000025D232B1800) [pid = 7864] [serial = 205] [outer = 0000025D2D5183E0][task 2019-08-15T12:13:32.392Z] 12:13:32 INFO - GECKO(8660) | [Parent 7864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805D0021: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 994
[task 2019-08-15T12:13:34.809Z] 12:13:34 INFO - GECKO(8660) | --DOCSHELL 0000020FA4B76000 == 0 [pid = 9320] [id = {b0cc66c3-1d21-4f06-b626-25010f35246d}] [url = about:blank]
[task 2019-08-15T12:13:35.518Z] 12:13:35 INFO - GECKO(8660) | --DOMWINDOW == 3 (0000025336AA3800) [pid = 2736] [serial = 39] [outer = 0000000000000000] [url = http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#8126094943660273]
[task 2019-08-15T12:13:35.518Z] 12:13:35 INFO - GECKO(8660) | --DOMWINDOW == 2 (00000253317DB000) [pid = 2736] [serial = 41] [outer = 0000000000000000] [url = about:blank]
[task 2019-08-15T12:13:39.146Z] 12:13:39 INFO - GECKO(8660) | --DOMWINDOW == 1 (0000020FA9F2A020) [pid = 9320] [serial = 29] [outer = 0000000000000000] [url = about:blank]
[task 2019-08-15T12:13:40.427Z] 12:13:40 INFO - GECKO(8660) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] received.
[task 2019-08-15T12:13:40.427Z] 12:13:40 INFO - GECKO(8660) | Waiting for event: 'Performance:BackendReadyRecordingStop' on [object PerformanceController] for 1 time(s).
[task 2019-08-15T12:13:40.427Z] 12:13:40 INFO - GECKO(8660) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-stopping"].
[task 2019-08-15T12:13:40.427Z] 12:13:40 INFO - GECKO(8660) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-stopped"].
[task 2019-08-15T12:13:40.427Z] 12:13:40 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView] for 1 time(s) with arguments: ["recorded"].
[task 2019-08-15T12:13:40.427Z] 12:13:40 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 1 time(s) with arguments: [16].
[task 2019-08-15T12:13:40.429Z] 12:13:40 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:WaterfallRendered' on [object WaterfallView] for 1 time(s).
[task 2019-08-15T12:13:42.852Z] 12:13:42 INFO - GECKO(8660) | Ignoring event 'Performance:UI:StateChanged' with unexpected argument at index 0: loading - expected recorded
[task 2019-08-15T12:13:42.862Z] 12:13:42 INFO - GECKO(8660) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2019-08-15T12:13:42.862Z] 12:13:42 INFO - GECKO(8660) | Ignoring event 'Performance:RecordingStateChange' with unexpected argument at index 0: recording-stopping - expected recording-stopped
[task 2019-08-15T12:13:43.165Z] 12:13:43 INFO - GECKO(8660) | --DOMWINDOW == 0 (0000020FA9FB5000) [pid = 9320] [serial = 31] [outer = 0000000000000000] [url = about:blank]
[task 2019-08-15T12:13:43.187Z] 12:13:43 INFO - GECKO(8660) | Ignoring event 'Performance:UI:OverviewRendered' with unexpected argument at index 0: 100 - expected 16
[task 2019-08-15T12:13:46.281Z] 12:13:46 INFO - GECKO(8660) | [Child 2736, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-08-15T12:13:47.063Z] 12:13:47 INFO - GECKO(8660) | Event: 'Performance:UI:StateChanged' on [object PerformanceView] received.
[task 2019-08-15T12:13:47.081Z] 12:13:47 INFO - GECKO(8660) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2019-08-15T12:13:47.850Z] 12:13:47 INFO - GECKO(8660) | Event: 'Performance:UI:WaterfallRendered' on [object WaterfallView] received.
[task 2019-08-15T12:13:47.899Z] 12:13:47 INFO - GECKO(8660) | Event: 'Performance:BackendReadyRecordingStop' on [object PerformanceController] received.
[task 2019-08-15T12:14:35.145Z] 12:14:35 INFO - GECKO(8660) | --DOMWINDOW == 2 (0000019B5FECC400) [pid = 5096] [serial = 28] [outer = 0000000000000000] [url = about:blank]
[task 2019-08-15T12:15:08.853Z] 12:15:08 INFO - GECKO(8660) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] received.
[task 2019-08-15T12:15:08.858Z] 12:15:08 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:JsCallTreeRendered' on [object JsCallTreeView] for 1 time(s).
[task 2019-08-15T12:15:08.985Z] 12:15:08 INFO - GECKO(8660) | Event: 'Performance:UI:JsCallTreeRendered' on [object JsCallTreeView] received.
[task 2019-08-15T12:15:08.987Z] 12:15:08 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:DetailsViewSelected' on [object DetailsView] for 1 time(s).
[task 2019-08-15T12:15:08.997Z] 12:15:08 INFO - GECKO(8660) | Event: 'Performance:UI:DetailsViewSelected' on [object DetailsView] received.
[task 2019-08-15T12:15:08.997Z] 12:15:08 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:WaterfallRendered' on [object JsCallTreeView] for 1 time(s).
[task 2019-08-15T12:15:09.238Z] 12:15:09 INFO - GECKO(8660) | Waiting for event: 'Performance:BackendReadyRecordingStart' on [object PerformanceController] for 1 time(s).
[task 2019-08-15T12:15:09.240Z] 12:15:09 INFO - GECKO(8660) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-started"].
[task 2019-08-15T12:15:09.240Z] 12:15:09 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView] for 1 time(s) with arguments: [{}].
[task 2019-08-15T12:15:09.240Z] 12:15:09 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 1 time(s) with arguments: [100].
[task 2019-08-15T12:15:09.340Z] 12:15:09 INFO - [2432, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-08-15T12:15:43.681Z] 12:15:43 INFO - GECKO(8660) | Ignoring event 'Performance:UI:StateChanged' with unexpected argument at index 0: recorded - expected /^(console-)?recording$/[task 2019-08-15T12:15:43.691Z] 12:15:43 INFO - GECKO(8660) | Event: 'Performance:UI:StateChanged' on [object PerformanceView] received.
[task 2019-08-15T12:15:43.780Z] 12:15:43 INFO - GECKO(8660) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2019-08-15T12:15:43.845Z] 12:15:43 INFO - GECKO(8660) | Event: 'Performance:BackendReadyRecordingStart' on [object PerformanceController] received.
[task 2019-08-15T12:16:14.210Z] 12:16:14 INFO - GECKO(8660) | --DOMWINDOW == 32 (0000025D2253E020) [pid = 7864] [serial = 189] [outer = 0000000000000000] [url = chrome://devtools/content/performance/index.xul]
[task 2019-08-15T12:16:14.210Z] 12:16:14 INFO - GECKO(8660) | --DOMWINDOW == 31 (0000025D2D518D40) [pid = 7864] [serial = 193] [outer = 0000000000000000] [url = chrome://devtools/content/shared/widgets/graphs-frame.xhtml]
[task 2019-08-15T12:16:14.210Z] 12:16:14 INFO - GECKO(8660) | --DOMWINDOW == 30 (0000025D2D518200) [pid = 7864] [serial = 195] [outer = 0000000000000000] [url = chrome://devtools/content/shared/widgets/graphs-frame.xhtml]
[task 2019-08-15T12:16:14.210Z] 12:16:14 INFO - GECKO(8660) | --DOMWINDOW == 29 (0000025D218E2020) [pid = 7864] [serial = 186] [outer = 0000000000000000] [url = about:devtools-toolbox]
[task 2019-08-15T12:16:14.210Z] 12:16:14 INFO - GECKO(8660) | --DOMWINDOW == 28 (0000025D283354C0) [pid = 7864] [serial = 191] [outer = 0000000000000000] [url = chrome://devtools/content/shared/widgets/graphs-frame.xhtml]
[task 2019-08-15T12:16:52.532Z] 12:16:52 INFO - GECKO(8660) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] received.
[task 2019-08-15T12:16:52.534Z] 12:16:52 INFO - GECKO(8660) | Waiting for event: 'Performance:BackendReadyRecordingStop' on [object PerformanceController] for 1 time(s).
[task 2019-08-15T12:16:52.534Z] 12:16:52 INFO - GECKO(8660) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-stopping"].
[task 2019-08-15T12:16:52.534Z] 12:16:52 INFO - GECKO(8660) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-stopped"].
[task 2019-08-15T12:16:52.534Z] 12:16:52 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView] for 1 time(s) with arguments: ["recorded"].
[task 2019-08-15T12:16:52.534Z] 12:16:52 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 1 time(s) with arguments: [16].
[task 2019-08-15T12:16:52.536Z] 12:16:52 INFO - GECKO(8660) | Waiting for event: 'Performance:UI:WaterfallRendered' on [object WaterfallView] for 1 time(s).
[task 2019-08-15T12:17:07.208Z] 12:17:07 INFO - GECKO(8660) | [Child 2736, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-08-15T12:17:19.910Z] 12:17:19 INFO - GECKO(8660) | Ignoring event 'Performance:UI:StateChanged' with unexpected argument at index 0: loading - expected recorded
[task 2019-08-15T12:17:19.920Z] 12:17:19 INFO - GECKO(8660) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2019-08-15T12:17:19.920Z] 12:17:19 INFO - GECKO(8660) | Ignoring event 'Performance:RecordingStateChange' with unexpected argument at index 0: recording-stopping - expected recording-stopped
[task 2019-08-15T12:17:20.120Z] 12:17:20 INFO - GECKO(8660) | Event: 'Performance:UI:StateChanged' on [object PerformanceView] received.
[task 2019-08-15T12:17:21.917Z] 12:17:21 INFO - GECKO(8660) | Event: 'Performance:UI:WaterfallRendered' on [object WaterfallView] received.
[task 2019-08-15T12:17:21.917Z] 12:17:21 INFO - GECKO(8660) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2019-08-15T12:17:21.997Z] 12:17:21 INFO - GECKO(8660) | Event: 'Performance:BackendReadyRecordingStop' on [object PerformanceController] received.
[task 2019-08-15T12:17:38.385Z] 12:17:38 INFO - GECKO(8660) | --DOMWINDOW == 27 (0000025D218CAC00) [pid = 7864] [serial = 198] [outer = 0000000000000000] [url = about:blank]
[task 2019-08-15T12:18:02.087Z] 12:18:02 INFO - JavaScript error: resource://gre/modules/PlacesExpiration.jsm, line 851: TypeError: this._db is undefined
[task 2019-08-15T12:18:25.419Z] 12:18:25 INFO - GECKO(8660) | Ignoring event 'Performance:UI:OverviewRendered' with unexpected argument at index 0: 100 - expected 16
[task 2019-08-15T12:18:30.160Z] 12:18:30 INFO - TEST-INFO | started process screenshot
[task 2019-08-15T12:18:30.241Z] 12:18:30 INFO - TEST-INFO | screenshot: exit 0
[task 2019-08-15T12:18:30.241Z] 12:18:30 INFO - Buffered messages logged at 12:13:26
[task 2019-08-15T12:18:30.241Z] 12:18:30 INFO - Entering test bound
[task 2019-08-15T12:18:30.241Z] 12:18:30 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
[task 2019-08-15T12:18:30.242Z] 12:18:30 INFO - Buffered messages logged at 12:15:09
[task 2019-08-15T12:18:30.242Z] 12:18:30 INFO - Longer timeout required, waiting longer... Remaining timeouts: 2
[task 2019-08-15T12:18:30.242Z] 12:18:30 INFO - Buffered messages logged at 12:16:50
[task 2019-08-15T12:18:30.242Z] 12:18:30 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2019-08-15T12:18:30.242Z] 12:18:30 INFO - Buffered messages finished
[task 2019-08-15T12:18:30.242Z] 12:18:30 INFO - TEST-UNEXPECTED-FAIL | devtools/client/performance/test/browser_perf-details-07-bleed-events.js | Test timed out -
| Comment hidden (Intermittent Failures Robot) |
Comment 2•6 years ago
|
||
| Comment hidden (Intermittent Failures Robot) |
Comment 4•6 years ago
|
||
New failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=268210527&repo=mozilla-central&lineNumber=5071
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 8•6 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Description
•