Closed Bug 1538073 Opened 6 years ago Closed 6 years ago

Intermittent devtools/server/tests/browser/browser_perf-profiler-01.js | Test timed out -

Categories

(DevTools :: General, defect, P5)

Product:
DevTools
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

#[markdown(off)]
Filed by: opoprus [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=235322307&repo=mozilla-central

https://queue.taskcluster.net/v1/task/Mc-VDjvXTRu_Xhr_lCtLsg/runs/0/artifacts/public/logs/live_backing.log

[task 2019-03-22T02:47:50.375Z] 02:47:50 INFO - TEST-START | devtools/server/tests/browser/browser_perf-profiler-01.js
[task 2019-03-22T02:47:50.565Z] 02:47:50 INFO - GECKO(2021) | ++DOCSHELL 0x7fa06c882800 == 2 [pid = 2207] [id = {1ee15a27-8747-47be-a668-8b60399fc682}]
[task 2019-03-22T02:47:50.566Z] 02:47:50 INFO - GECKO(2021) | ++DOMWINDOW == 4 (0x7fa06c8eb200) [pid = 2207] [serial = 166] [outer = (nil)]
[task 2019-03-22T02:47:50.622Z] 02:47:50 INFO - GECKO(2021) | ++DOMWINDOW == 5 (0x7fa06c8b4400) [pid = 2207] [serial = 167] [outer = 0x7fa06c8eb200]
[task 2019-03-22T02:47:50.805Z] 02:47:50 INFO - GECKO(2021) | ++DOMWINDOW == 6 (0x7fa06c8aec00) [pid = 2207] [serial = 168] [outer = 0x7fa06c8eb200]
[task 2019-03-22T02:48:13.384Z] 02:48:13 INFO - GECKO(2021) | --DOCSHELL 0x7fb2c787f800 == 0 [pid = 2104] [id = {58a41190-ebec-447e-98c3-4c4451b40377}] [url = about:blank]
[task 2019-03-22T02:48:13.638Z] 02:48:13 INFO - GECKO(2021) | --DOMWINDOW == 1 (0x7fb2c7828200) [pid = 2104] [serial = 90] [outer = (nil)] [url = about:blank]
[task 2019-03-22T02:48:41.343Z] 02:48:41 INFO - GECKO(2021) | --DOMWINDOW == 0 (0x7fb2c678f400) [pid = 2104] [serial = 92] [outer = (nil)] [url = about:blank]
[task 2019-03-22T02:49:25.376Z] 02:49:25 INFO - TEST-INFO | started process screentopng
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Failed to start /builds/worker/workspace/build/tests/bin/screentopng for screenshot: Cannot allocate memory
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Buffered messages logged at 02:47:50
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Entering test bound
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Adding a new tab with URL: http://test1.example.org/browser/devtools/server/tests/browser/doc_perf.html
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Tab added a URL http://test1.example.org/browser/devtools/server/tests/browser/doc_perf.html loaded
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Buffered messages logged at 02:47:51
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Attaching to the active tab.
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - TEST-PASS | devtools/server/tests/browser/browser_perf-profiler-01.js | The built-in profiler module should not have been automatically started. -
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - Buffered messages finished
[task 2019-03-22T02:49:25.380Z] 02:49:25 INFO - TEST-UNEXPECTED-FAIL | devtools/server/tests/browser/browser_perf-profiler-01.js | Test timed out -
[task 2019-03-22T02:49:25.388Z] 02:49:25 INFO - Removing tab.
[task 2019-03-22T02:49:25.404Z] 02:49:25 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-03-22T02:49:26.109Z] 02:49:26 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-03-22T02:49:27.111Z] 02:49:27 INFO - Tab removed and finished closing
[task 2019-03-22T02:49:27.138Z] 02:49:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - TEST-UNEXPECTED-FAIL | devtools/server/tests/browser/browser_perf-profiler-01.js | Uncaught exception received from previously timed out test - at resource://devtools/shared/protocol.js:1299 - Error: Connection closed, pending request to server1.conn2.child1/performanceActor10, type stopRecording failed
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO -
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - Request stack:
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - request@resource://devtools/shared/protocol.js:1386:14
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - @chrome://mochitests/content/browser/devtools/server/tests/browser/browser_perf-profiler-01.js:25:15
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1106:34
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1134:12
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:995:14
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO -
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - Stack trace:
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - destroy@resource://devtools/shared/protocol.js:1299:23
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - TargetMixin/destroy/this._destroyer<@resource://devtools/shared/fronts/targets/target-mixin.js:516:23
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - async*destroy@resource://devtools/shared/fronts/targets/target-mixin.js:556:9
[task 2019-03-22T02:49:27.139Z] 02:49:27 INFO - destroy@resource://devtools/shared/fronts/targets/browsing-context.js:140:27
[task 2019-03-22T02:49:27.140Z] 02:49:27 INFO - handleEvent@resource://devtools/shared/fronts/targets/target-mixin.js:464:16
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - _beginRemoveTab@chrome://browser/content/tabbrowser.js:2993:10
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - removeTab@chrome://browser/content/tabbrowser.js:2815:15
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - removeTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:191:12
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - closeTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:457:9
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - async*cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:136:11
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - nextTest@chrome://mochikit/content/browser-test.js:705:35
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - async*timeoutFn@chrome://mochikit/content/browser-test.js:1202:14
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1164:45
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:995:14
[task 2019-03-22T02:49:27.141Z] 02:49:27 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59
[task 2019-03-22T02:49:29.413Z] 02:49:29 INFO - GECKO(2021) | MEMORY STAT | vsize 2476MB | residentFast 513MB | heapAllocated 278MB
[task 2019-03-22T02:49:29.414Z] 02:49:29 INFO - TEST-OK | devtools/server/tests/browser/browser_perf-profiler-01.js | took 99004ms
[task 2019-03-22T02:49:30.065Z] 02:49:30 INFO - GECKO(2021) | ++DOCSHELL 0x7fb2c787e000 == 1 [pid = 2104] [id = {e08c9653-cffb-47ad-a9d1-7a65a34814ff}]
[task 2019-03-22T02:49:30.068Z] 02:49:30 INFO - GECKO(2021) | ++DOMWINDOW == 1 (0x7fb2c7828020) [pid = 2104] [serial = 93] [outer = (nil)]
[task 2019-03-22T02:49:30.508Z] 02:49:30 INFO - GECKO(2021) | ++DOMWINDOW == 2 (0x7fb2c678a000) [pid = 2104] [serial = 94] [outer = 0x7fb2c7828020]
[task 2019-03-22T02:49:33.259Z] 02:49:33 INFO - GECKO(2021) | ++DOMWINDOW == 3 (0x7fb2c678b000) [pid = 2104] [serial = 95] [outer = 0x7fb2c7828020]
[task 2019-03-22T02:49:37.678Z] 02:49:37 INFO - checking window state

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

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.