Closed Bug 1619578 Opened 5 years ago Closed 5 years ago

Perma beta TEST-UNEXPECTED-FAIL | devtools/client/performance-new/test/browser/browser_webchannel-enable-menu-button.js | Test timed out - when Gecko 75 merges to Beta on 2020-03-09

Tracking

(firefox-esr68 unaffected, firefox73 unaffected, firefox74 unaffected, firefox75+ verified)

Status:

VERIFIED FIXED

Milestone:

Firefox 75

Tracking Flags:

Tracking

Status

firefox-esr68

---

unaffected

firefox73

---

unaffected

firefox74

---

unaffected

firefox75

verified

People

(Reporter: dluca, Assigned: gregtatum)

References

(Regression)

Details

(Keywords: regression)

Attachments

(1 file)

Bug 1619578 - Make the profiler WebChannel work on beta; r?canaltinova 5 years ago Greg Tatum [:gregtatum] (REO Fx132) 47 bytes, text/x-phabricator-request		Details \| Review

Dorel Luca [:dluca]

Reporter

Description

•

5 years ago

Trunk as beta:
https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel%2Crunnable&revision=9dfc5f68457798584784b497b06724db7d408fe2&selectedJob=291415633

Log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=291415633&repo=try&lineNumber=6092

INFO - TEST-START | devtools/client/performance-new/test/browser/browser_webchannel-enable-menu-button.js
[task 2020-03-03T11:31:35.862Z] 11:31:35     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fbef40aa000 == 3 [pid = 2672] [id = {94bd34aa-40e9-48ef-bf83-1adb35dc20a5}]
[task 2020-03-03T11:31:35.863Z] 11:31:35     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0x7fbf2968d8c0) [pid = 2672] [serial = 10] [outer = (nil)]
[task 2020-03-03T11:31:35.864Z] 11:31:35     INFO - GECKO(2493) | [Child 2672, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2020-03-03T11:31:35.864Z] 11:31:35     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7fbf14860000) [pid = 2672] [serial = 11] [outer = 0x7fbf2968d8c0]
[task 2020-03-03T11:31:36.021Z] 11:31:36     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7fbf149c7400) [pid = 2672] [serial = 12] [outer = 0x7fbf2968d8c0]
[task 2020-03-03T11:31:36.405Z] 11:31:36     INFO - GECKO(2493) | [Child 2794: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fa5c1f15000 == 0 [pid = 2794] [id = {08bf63da-236c-43a7-9902-23729491e156}] [url = about:blank]
[task 2020-03-03T11:31:37.347Z] 11:31:37     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 22 (0x7f20cb490400) [pid = 2493] [serial = 22] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:37.347Z] 11:31:37     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (0x7f20cb442000) [pid = 2493] [serial = 19] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:38.293Z] 11:31:38     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fbf0f6bf800 == 2 [pid = 2672] [id = {f9014bcd-378b-410e-a015-37aa2a7d9146}] [url = http://example.com/browser/devtools/client/performance-new/test/browser/fake-frontend.html]
[task 2020-03-03T11:31:38.294Z] 11:31:38     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fbef40a7800 == 1 [pid = 2672] [id = {e16b1d60-e333-416a-a39c-15eccf9024fc}] [url = about:blank]
[task 2020-03-03T11:31:38.317Z] 11:31:38     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7fbf2968d520) [pid = 2672] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:39.784Z] 11:31:39     INFO - GECKO(2493) | [Child 2722: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f6f5788d520) [pid = 2722] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:40.012Z] 11:31:40     INFO - GECKO(2493) | [Child 2873: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f5e68115000 == 0 [pid = 2873] [id = {5c371128-b078-4ced-9aef-338f279117f4}] [url = about:blank]
[task 2020-03-03T11:31:42.365Z] 11:31:42     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fbf14860c00) [pid = 2672] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:42.365Z] 11:31:42     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7fbf0f6b4c00) [pid = 2672] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:42.366Z] 11:31:42     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7fbf14860000) [pid = 2672] [serial = 11] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:42.367Z] 11:31:42     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7fbf148e7400) [pid = 2672] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:42.367Z] 11:31:42     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7fbf0f6ad000) [pid = 2672] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:42.402Z] 11:31:42     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7fbf2968d350) [pid = 2672] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:42.403Z] 11:31:42     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7fbf2968d6f0) [pid = 2672] [serial = 3] [outer = (nil)] [url = http://example.com/browser/devtools/client/performance-new/test/browser/fake-frontend.html]
[task 2020-03-03T11:31:43.764Z] 11:31:43     INFO - GECKO(2493) | [Child 2918: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f9d7b614800 == 0 [pid = 2918] [id = {b22d302f-05e8-45d9-9da1-f2dfb6063bbf}] [url = about:blank]
[task 2020-03-03T11:31:43.780Z] 11:31:43     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (0x7f20cc21ec00) [pid = 2493] [serial = 25] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:43.807Z] 11:31:43     INFO - GECKO(2493) | [Child 2722: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f6f3cf15c00) [pid = 2722] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:43.808Z] 11:31:43     INFO - GECKO(2493) | [Child 2722: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f6f3d857000) [pid = 2722] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:44.538Z] 11:31:44     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (0x7f20d6df8d40) [pid = 2493] [serial = 15] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:31:44.539Z] 11:31:44     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (0x7f20d6df8600) [pid = 2493] [serial = 12] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:31:46.442Z] 11:31:46     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fbf0f6b5400) [pid = 2672] [serial = 5] [outer = (nil)] [url = http://example.com/browser/devtools/client/performance-new/test/browser/fake-frontend.html]
[task 2020-03-03T11:31:46.443Z] 11:31:46     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fbf148ed400) [pid = 2672] [serial = 9] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:31:50.005Z] 11:31:50     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (0x7f20cb443c00) [pid = 2493] [serial = 17] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:31:50.005Z] 11:31:50     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (0x7f20d2406400) [pid = 2493] [serial = 14] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:36:08.630Z] 11:36:08     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f20ca104800 == 7 [pid = 2493] [id = {7525eb10-12d5-42d9-891a-a68961f58a23}] [url = about:profiling]
[task 2020-03-03T11:36:08.630Z] 11:36:08     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f20ca106800 == 6 [pid = 2493] [id = {e943557d-27d1-4b4b-8478-7db2092fdade}] [url = about:profiling]
[task 2020-03-03T11:36:08.632Z] 11:36:08     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f20ca113000 == 5 [pid = 2493] [id = {9575e026-f622-47bf-b01d-42def18e4da7}] [url = about:profiling]
[task 2020-03-03T11:36:09.417Z] 11:36:09     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (0x7f20d6df9f60) [pid = 2493] [serial = 24] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:36:09.418Z] 11:36:09     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7f20d6df9820) [pid = 2493] [serial = 21] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:36:09.418Z] 11:36:09     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0x7f20d6df9480) [pid = 2493] [serial = 18] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:36:14.618Z] 11:36:14     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0x7f20cb440400) [pid = 2493] [serial = 20] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:36:14.619Z] 11:36:14     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7f20d1de6800) [pid = 2493] [serial = 23] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:36:14.619Z] 11:36:14     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7f20cc2cf400) [pid = 2493] [serial = 26] [outer = (nil)] [url = about:profiling]
[task 2020-03-03T11:36:22.328Z] 11:36:22     INFO - GECKO(2493) | [Child 2747: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f372608d520) [pid = 2747] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:22.348Z] 11:36:22     INFO - GECKO(2493) | [Child 2769: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f498608d520) [pid = 2769] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:26.361Z] 11:36:26     INFO - GECKO(2493) | [Child 2747: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f370c05b000) [pid = 2747] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:26.377Z] 11:36:26     INFO - GECKO(2493) | [Child 2769: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f49619d4400) [pid = 2769] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:29.968Z] 11:36:29     INFO - GECKO(2493) | [Child 2794: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fa5dcb8d520) [pid = 2794] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:32.747Z] 11:36:32     INFO - GECKO(2493) | [Child 2873: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f5e82d8d520) [pid = 2873] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:34.013Z] 11:36:34     INFO - GECKO(2493) | [Child 2794: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fa5b8be6800) [pid = 2794] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:36.607Z] 11:36:36     INFO - GECKO(2493) | [Child 2918: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f9d9638d520) [pid = 2918] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:36.791Z] 11:36:36     INFO - GECKO(2493) | [Child 2873: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f5e5e6c6800) [pid = 2873] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:36:40.649Z] 11:36:40     INFO - GECKO(2493) | [Child 2918: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f9d7ba36800) [pid = 2918] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:37:31.465Z] 11:37:31     INFO - GECKO(2493) | [2020-03-03T11:37:31Z WARN  rkv::backend::impl_safe::environment] Ignoring `map_size=16777216`
[task 2020-03-03T11:48:05.856Z] 11:48:05     INFO - TEST-INFO | started process screentopng
[task 2020-03-03T11:48:06.150Z] 11:48:06     INFO - TEST-INFO | screentopng: exit 0
[task 2020-03-03T11:48:06.150Z] 11:48:06     INFO - <snipped 899 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2020-03-03T11:48:06.150Z] 11:48:06     INFO - Buffered messages logged at 11:46:26
[task 2020-03-03T11:48:06.150Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 890 seconds)
[task 2020-03-03T11:48:06.150Z] 11:48:06     INFO - Buffered messages logged at 11:46:27
[task 2020-03-03T11:48:06.150Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 891 seconds)
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - Buffered messages logged at 11:46:28
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 892 seconds)
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - Buffered messages logged at 11:46:29
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 893 seconds)
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - Buffered messages logged at 11:46:30
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 894 seconds)
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - Buffered messages logged at 11:46:31
[task 2020-03-03T11:48:06.151Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 895 seconds)
[task 2020-03-03T11:48:06.152Z] 11:48:06     INFO - Buffered messages logged at 11:46:32
[task 2020-03-03T11:48:06.154Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 896 seconds)
[task 2020-03-03T11:48:06.155Z] 11:48:06     INFO - Buffered messages logged at 11:46:33
[task 2020-03-03T11:48:06.155Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 897 seconds)
[task 2020-03-03T11:48:06.156Z] 11:48:06     INFO - Buffered messages logged at 11:46:34
[task 2020-03-03T11:48:06.157Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 898 seconds)
[task 2020-03-03T11:48:06.159Z] 11:48:06     INFO - Buffered messages logged at 11:46:35
[task 2020-03-03T11:48:06.159Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 899 seconds)
[task 2020-03-03T11:48:06.160Z] 11:48:06     INFO - Buffered messages logged at 11:46:36
[task 2020-03-03T11:48:06.162Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 900 seconds)
[task 2020-03-03T11:48:06.165Z] 11:48:06     INFO - Buffered messages logged at 11:46:37
[task 2020-03-03T11:48:06.166Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 901 seconds)
[task 2020-03-03T11:48:06.166Z] 11:48:06     INFO - Buffered messages logged at 11:46:38
[task 2020-03-03T11:48:06.167Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 902 seconds)
[task 2020-03-03T11:48:06.168Z] 11:48:06     INFO - Buffered messages logged at 11:46:39
[task 2020-03-03T11:48:06.168Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 903 seconds)
[task 2020-03-03T11:48:06.169Z] 11:48:06     INFO - Buffered messages logged at 11:46:40
[task 2020-03-03T11:48:06.173Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 904 seconds)
[task 2020-03-03T11:48:06.174Z] 11:48:06     INFO - Buffered messages logged at 11:46:41
[task 2020-03-03T11:48:06.176Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 905 seconds)
[task 2020-03-03T11:48:06.177Z] 11:48:06     INFO - Buffered messages logged at 11:46:42
[task 2020-03-03T11:48:06.177Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 906 seconds)
[task 2020-03-03T11:48:06.178Z] 11:48:06     INFO - Buffered messages logged at 11:46:43
[task 2020-03-03T11:48:06.179Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 907 seconds)
[task 2020-03-03T11:48:06.180Z] 11:48:06     INFO - Buffered messages logged at 11:46:44
[task 2020-03-03T11:48:06.182Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 908 seconds)
[task 2020-03-03T11:48:06.186Z] 11:48:06     INFO - Buffered messages logged at 11:46:45
[task 2020-03-03T11:48:06.189Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 909 seconds)
[task 2020-03-03T11:48:06.190Z] 11:48:06     INFO - Buffered messages logged at 11:46:46
[task 2020-03-03T11:48:06.191Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 910 seconds)
[task 2020-03-03T11:48:06.194Z] 11:48:06     INFO - Buffered messages logged at 11:46:47
[task 2020-03-03T11:48:06.195Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 911 seconds)
[task 2020-03-03T11:48:06.196Z] 11:48:06     INFO - Buffered messages logged at 11:46:48
[task 2020-03-03T11:48:06.196Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 912 seconds)
[task 2020-03-03T11:48:06.197Z] 11:48:06     INFO - Buffered messages logged at 11:46:49
[task 2020-03-03T11:48:06.198Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 913 seconds)
[task 2020-03-03T11:48:06.225Z] 11:48:06     INFO - Buffered messages logged at 11:46:50
[task 2020-03-03T11:48:06.227Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 914 seconds)
[task 2020-03-03T11:48:06.228Z] 11:48:06     INFO - Buffered messages logged at 11:46:51
[task 2020-03-03T11:48:06.229Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 915 seconds)
[task 2020-03-03T11:48:06.229Z] 11:48:06     INFO - Buffered messages logged at 11:46:52
[task 2020-03-03T11:48:06.230Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 916 seconds)
[task 2020-03-03T11:48:06.232Z] 11:48:06     INFO - Buffered messages logged at 11:46:53
[task 2020-03-03T11:48:06.232Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 917 seconds)
[task 2020-03-03T11:48:06.233Z] 11:48:06     INFO - Buffered messages logged at 11:46:54
[task 2020-03-03T11:48:06.233Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 918 seconds)
[task 2020-03-03T11:48:06.234Z] 11:48:06     INFO - Buffered messages logged at 11:46:55
[task 2020-03-03T11:48:06.234Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 919 seconds)
[task 2020-03-03T11:48:06.235Z] 11:48:06     INFO - Buffered messages logged at 11:46:56
[task 2020-03-03T11:48:06.236Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 920 seconds)
[task 2020-03-03T11:48:06.237Z] 11:48:06     INFO - Buffered messages logged at 11:46:57
[task 2020-03-03T11:48:06.240Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 921 seconds)
[task 2020-03-03T11:48:06.241Z] 11:48:06     INFO - Buffered messages logged at 11:46:58
[task 2020-03-03T11:48:06.241Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 922 seconds)
[task 2020-03-03T11:48:06.244Z] 11:48:06     INFO - Buffered messages logged at 11:46:59
[task 2020-03-03T11:48:06.244Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 923 seconds)
[task 2020-03-03T11:48:06.244Z] 11:48:06     INFO - Buffered messages logged at 11:47:00
[task 2020-03-03T11:48:06.249Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 924 seconds)
[task 2020-03-03T11:48:06.251Z] 11:48:06     INFO - Buffered messages logged at 11:47:01
[task 2020-03-03T11:48:06.251Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 925 seconds)
[task 2020-03-03T11:48:06.251Z] 11:48:06     INFO - Buffered messages logged at 11:47:02
[task 2020-03-03T11:48:06.252Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 926 seconds)
[task 2020-03-03T11:48:06.252Z] 11:48:06     INFO - Buffered messages logged at 11:47:03
[task 2020-03-03T11:48:06.252Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 927 seconds)
[task 2020-03-03T11:48:06.252Z] 11:48:06     INFO - Buffered messages logged at 11:47:04
[task 2020-03-03T11:48:06.253Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 928 seconds)
[task 2020-03-03T11:48:06.253Z] 11:48:06     INFO - Buffered messages logged at 11:47:05
[task 2020-03-03T11:48:06.253Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 929 seconds)
[task 2020-03-03T11:48:06.255Z] 11:48:06     INFO - Buffered messages logged at 11:47:06
[task 2020-03-03T11:48:06.255Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 930 seconds)
[task 2020-03-03T11:48:06.260Z] 11:48:06     INFO - Buffered messages logged at 11:47:07
[task 2020-03-03T11:48:06.261Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 931 seconds)
[task 2020-03-03T11:48:06.261Z] 11:48:06     INFO - Buffered messages logged at 11:47:08
[task 2020-03-03T11:48:06.261Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 932 seconds)
[task 2020-03-03T11:48:06.261Z] 11:48:06     INFO - Buffered messages logged at 11:47:09
[task 2020-03-03T11:48:06.264Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 933 seconds)
[task 2020-03-03T11:48:06.264Z] 11:48:06     INFO - Buffered messages logged at 11:47:10
[task 2020-03-03T11:48:06.268Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 934 seconds)
[task 2020-03-03T11:48:06.269Z] 11:48:06     INFO - Buffered messages logged at 11:47:11
[task 2020-03-03T11:48:06.269Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 935 seconds)
[task 2020-03-03T11:48:06.270Z] 11:48:06     INFO - Buffered messages logged at 11:47:12
[task 2020-03-03T11:48:06.271Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 936 seconds)
[task 2020-03-03T11:48:06.271Z] 11:48:06     INFO - Buffered messages logged at 11:47:13
[task 2020-03-03T11:48:06.272Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 937 seconds)
[task 2020-03-03T11:48:06.273Z] 11:48:06     INFO - Buffered messages logged at 11:47:14
[task 2020-03-03T11:48:06.273Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 938 seconds)
[task 2020-03-03T11:48:06.274Z] 11:48:06     INFO - Buffered messages logged at 11:47:15
[task 2020-03-03T11:48:06.275Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 939 seconds)
[task 2020-03-03T11:48:06.276Z] 11:48:06     INFO - Buffered messages logged at 11:47:16
[task 2020-03-03T11:48:06.278Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 940 seconds)
[task 2020-03-03T11:48:06.279Z] 11:48:06     INFO - Buffered messages logged at 11:47:17
[task 2020-03-03T11:48:06.280Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 941 seconds)
[task 2020-03-03T11:48:06.280Z] 11:48:06     INFO - Buffered messages logged at 11:47:18
[task 2020-03-03T11:48:06.281Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 942 seconds)
[task 2020-03-03T11:48:06.282Z] 11:48:06     INFO - Buffered messages logged at 11:47:19
[task 2020-03-03T11:48:06.282Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 943 seconds)
[task 2020-03-03T11:48:06.283Z] 11:48:06     INFO - Buffered messages logged at 11:47:20
[task 2020-03-03T11:48:06.290Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 944 seconds)
[task 2020-03-03T11:48:06.316Z] 11:48:06     INFO - Buffered messages logged at 11:47:21
[task 2020-03-03T11:48:06.316Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 945 seconds)
[task 2020-03-03T11:48:06.317Z] 11:48:06     INFO - Buffered messages logged at 11:47:22
[task 2020-03-03T11:48:06.317Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 946 seconds)
[task 2020-03-03T11:48:06.318Z] 11:48:06     INFO - Buffered messages logged at 11:47:23
[task 2020-03-03T11:48:06.319Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 947 seconds)
[task 2020-03-03T11:48:06.319Z] 11:48:06     INFO - Buffered messages logged at 11:47:24
[task 2020-03-03T11:48:06.321Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 948 seconds)
[task 2020-03-03T11:48:06.321Z] 11:48:06     INFO - Buffered messages logged at 11:47:25
[task 2020-03-03T11:48:06.321Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 949 seconds)
[task 2020-03-03T11:48:06.323Z] 11:48:06     INFO - Buffered messages logged at 11:47:26
[task 2020-03-03T11:48:06.323Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 950 seconds)
[task 2020-03-03T11:48:06.325Z] 11:48:06     INFO - Buffered messages logged at 11:47:27
[task 2020-03-03T11:48:06.325Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 951 seconds)
[task 2020-03-03T11:48:06.326Z] 11:48:06     INFO - Buffered messages logged at 11:47:28
[task 2020-03-03T11:48:06.327Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 952 seconds)
[task 2020-03-03T11:48:06.329Z] 11:48:06     INFO - Buffered messages logged at 11:47:29
[task 2020-03-03T11:48:06.330Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 953 seconds)
[task 2020-03-03T11:48:06.331Z] 11:48:06     INFO - Buffered messages logged at 11:47:30
[task 2020-03-03T11:48:06.331Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 954 seconds)
[task 2020-03-03T11:48:06.333Z] 11:48:06     INFO - Buffered messages logged at 11:47:31
[task 2020-03-03T11:48:06.334Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 955 seconds)
[task 2020-03-03T11:48:06.334Z] 11:48:06     INFO - Buffered messages logged at 11:47:32
[task 2020-03-03T11:48:06.335Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 956 seconds)
[task 2020-03-03T11:48:06.335Z] 11:48:06     INFO - Buffered messages logged at 11:47:33
[task 2020-03-03T11:48:06.335Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 957 seconds)
[task 2020-03-03T11:48:06.336Z] 11:48:06     INFO - Buffered messages logged at 11:47:34
[task 2020-03-03T11:48:06.343Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 958 seconds)
[task 2020-03-03T11:48:06.343Z] 11:48:06     INFO - Buffered messages logged at 11:47:35
[task 2020-03-03T11:48:06.343Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 959 seconds)
[task 2020-03-03T11:48:06.344Z] 11:48:06     INFO - Buffered messages logged at 11:47:36
[task 2020-03-03T11:48:06.344Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 960 seconds)
[task 2020-03-03T11:48:06.350Z] 11:48:06     INFO - Buffered messages logged at 11:47:37
[task 2020-03-03T11:48:06.351Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 961 seconds)
[task 2020-03-03T11:48:06.354Z] 11:48:06     INFO - Buffered messages logged at 11:47:38
[task 2020-03-03T11:48:06.354Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 962 seconds)
[task 2020-03-03T11:48:06.355Z] 11:48:06     INFO - Buffered messages logged at 11:47:39
[task 2020-03-03T11:48:06.356Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 963 seconds)
[task 2020-03-03T11:48:06.357Z] 11:48:06     INFO - Buffered messages logged at 11:47:40
[task 2020-03-03T11:48:06.359Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 964 seconds)
[task 2020-03-03T11:48:06.360Z] 11:48:06     INFO - Buffered messages logged at 11:47:41
[task 2020-03-03T11:48:06.361Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 965 seconds)
[task 2020-03-03T11:48:06.362Z] 11:48:06     INFO - Buffered messages logged at 11:47:42
[task 2020-03-03T11:48:06.374Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 966 seconds)
[task 2020-03-03T11:48:06.375Z] 11:48:06     INFO - Buffered messages logged at 11:47:43
[task 2020-03-03T11:48:06.375Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 967 seconds)
[task 2020-03-03T11:48:06.376Z] 11:48:06     INFO - Buffered messages logged at 11:47:44
[task 2020-03-03T11:48:06.377Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 968 seconds)
[task 2020-03-03T11:48:06.377Z] 11:48:06     INFO - Buffered messages logged at 11:47:45
[task 2020-03-03T11:48:06.378Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 969 seconds)
[task 2020-03-03T11:48:06.380Z] 11:48:06     INFO - Buffered messages logged at 11:47:46
[task 2020-03-03T11:48:06.394Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 970 seconds)
[task 2020-03-03T11:48:06.395Z] 11:48:06     INFO - Buffered messages logged at 11:47:47
[task 2020-03-03T11:48:06.396Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 971 seconds)
[task 2020-03-03T11:48:06.397Z] 11:48:06     INFO - Buffered messages logged at 11:47:48
[task 2020-03-03T11:48:06.398Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 972 seconds)
[task 2020-03-03T11:48:06.399Z] 11:48:06     INFO - Buffered messages logged at 11:47:49
[task 2020-03-03T11:48:06.400Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 973 seconds)
[task 2020-03-03T11:48:06.414Z] 11:48:06     INFO - Buffered messages logged at 11:47:50
[task 2020-03-03T11:48:06.416Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 974 seconds)
[task 2020-03-03T11:48:06.417Z] 11:48:06     INFO - Buffered messages logged at 11:47:51
[task 2020-03-03T11:48:06.419Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 975 seconds)
[task 2020-03-03T11:48:06.420Z] 11:48:06     INFO - Buffered messages logged at 11:47:52
[task 2020-03-03T11:48:06.428Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 976 seconds)
[task 2020-03-03T11:48:06.429Z] 11:48:06     INFO - Buffered messages logged at 11:47:53
[task 2020-03-03T11:48:06.430Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 977 seconds)
[task 2020-03-03T11:48:06.430Z] 11:48:06     INFO - Buffered messages logged at 11:47:54
[task 2020-03-03T11:48:06.434Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 978 seconds)
[task 2020-03-03T11:48:06.435Z] 11:48:06     INFO - Buffered messages logged at 11:47:55
[task 2020-03-03T11:48:06.436Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 979 seconds)
[task 2020-03-03T11:48:06.439Z] 11:48:06     INFO - Buffered messages logged at 11:47:56
[task 2020-03-03T11:48:06.440Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 980 seconds)
[task 2020-03-03T11:48:06.441Z] 11:48:06     INFO - Buffered messages logged at 11:47:57
[task 2020-03-03T11:48:06.446Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 981 seconds)
[task 2020-03-03T11:48:06.447Z] 11:48:06     INFO - Buffered messages logged at 11:47:58
[task 2020-03-03T11:48:06.448Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 982 seconds)
[task 2020-03-03T11:48:06.449Z] 11:48:06     INFO - Buffered messages logged at 11:47:59
[task 2020-03-03T11:48:06.450Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 983 seconds)
[task 2020-03-03T11:48:06.451Z] 11:48:06     INFO - Buffered messages logged at 11:48:00
[task 2020-03-03T11:48:06.452Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 984 seconds)
[task 2020-03-03T11:48:06.453Z] 11:48:06     INFO - Buffered messages logged at 11:48:01
[task 2020-03-03T11:48:06.454Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 985 seconds)
[task 2020-03-03T11:48:06.455Z] 11:48:06     INFO - Buffered messages logged at 11:48:02
[task 2020-03-03T11:48:06.456Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 986 seconds)
[task 2020-03-03T11:48:06.457Z] 11:48:06     INFO - Buffered messages logged at 11:48:03
[task 2020-03-03T11:48:06.458Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 987 seconds)
[task 2020-03-03T11:48:06.459Z] 11:48:06     INFO - Buffered messages logged at 11:48:04
[task 2020-03-03T11:48:06.460Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 988 seconds)
[task 2020-03-03T11:48:06.461Z] 11:48:06     INFO - Buffered messages logged at 11:48:05
[task 2020-03-03T11:48:06.462Z] 11:48:06     INFO - > Waiting until the profiler button is added to the browser. (After 989 seconds)
[task 2020-03-03T11:48:06.480Z] 11:48:06     INFO - Buffered messages finished
[task 2020-03-03T11:48:06.481Z] 11:48:06     INFO - TEST-UNEXPECTED-FAIL | devtools/client/performance-new/test/browser/browser_webchannel-enable-menu-button.js | Test timed out - 
[task 2020-03-03T11:48:06.484Z] 11:48:06     INFO - Revert the profiler menu button to its original enabled state.
[task 2020-03-03T11:48:06.485Z] 11:48:06     INFO - GECKO(2493) | MEMORY STAT | vsize 3278MB | residentFast 486MB | heapAllocated 255MB
[task 2020-03-03T11:48:06.487Z] 11:48:06     INFO - TEST-OK | devtools/client/performance-new/test/browser/browser_webchannel-enable-menu-button.js | took 990025ms
[task 2020-03-03T11:48:06.488Z] 11:48:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-03T11:48:06.489Z] 11:48:06     INFO - TEST-UNEXPECTED-FAIL | devtools/client/performance-new/test/browser/browser_webchannel-enable-menu-button.js | Found a tab after previous test timed out: http://example.com/browser/devtools/client/performance-new/test/browser/webchannel.html - 
[task 2020-03-03T11:48:06.490Z] 11:48:06     INFO - GECKO(2493) | [Child 2747: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f370b417800 == 1 [pid = 2747] [id = {50081497-3962-4041-a83d-79a409aa19f4}]
[task 2020-03-03T11:48:06.491Z] 11:48:06     INFO - GECKO(2493) | [Child 2747: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f372608d520) [pid = 2747] [serial = 3] [outer = (nil)]
[task 2020-03-03T11:48:06.495Z] 11:48:06     INFO - GECKO(2493) | [Child 2747, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2020-03-03T11:48:06.496Z] 11:48:06     INFO - GECKO(2493) | [Child 2747: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f370b712400) [pid = 2747] [serial = 4] [outer = 0x7f372608d520]
[task 2020-03-03T11:48:06.497Z] 11:48:06     INFO - checking window state
[task 2020-03-03T11:48:06.500Z] 11:48:06     INFO - GECKO(2493) | [Child 2747: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f370b717400) [pid = 2747] [serial = 5] [outer = 0x7f372608d520]
[task 2020-03-03T11:48:06.502Z] 11:48:06     INFO - GECKO(2493) | [Parent 2493, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsFrameLoader.cpp, line 684
[task 2020-03-03T11:48:06.503Z] 11:48:06     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7f20cb437800) [pid = 2493] [serial = 27] [outer = 0x7f20fc391520]
[task 2020-03-03T11:48:06.505Z] 11:48:06     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7f20cb43a800) [pid = 2493] [serial = 28] [outer = 0x7f20fc391520]
[task 2020-03-03T11:48:06.506Z] 11:48:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-03T11:48:06.508Z] 11:48:06     INFO - TEST-UNEXPECTED-FAIL | devtools/client/performance-new/test/browser/browser_webchannel-enable-menu-button.js | Uncaught exception received from previously timed out test - at chrome://mochitests/content/browser/devtools/client/performance-new/test/browser/head.js:36 - ReferenceError: info is not defined
[task 2020-03-03T11:48:06.509Z] 11:48:06     INFO - Stack trace:
[task 2020-03-03T11:48:06.511Z] 11:48:06     INFO - createPeriodicLogger/<@chrome://mochitests/content/browser/devtools/client/performance-new/test/browser/head.js:36:9
[task 2020-03-03T11:48:06.512Z] 11:48:06     INFO - waitUntil@chrome://mochitests/content/browser/devtools/client/performance-new/test/browser/head.js:63:22
[task 2020-03-03T11:48:06.720Z] 11:48:06     INFO - GECKO(2493) | [Child 2722: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f6f3d8c1800 == 0 [pid = 2722] [id = {65ba9cca-dd32-4d5a-bc2c-7ddf65b9133d}] [url = about:blank]
[task 2020-03-03T11:48:06.736Z] 11:48:06     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fbef40aa000 == 0 [pid = 2672] [id = {94bd34aa-40e9-48ef-bf83-1adb35dc20a5}] [url = http://example.com/browser/devtools/client/performance-new/test/browser/webchannel.html]
[task 2020-03-03T11:48:06.777Z] 11:48:06     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fbf2968d8c0) [pid = 2672] [serial = 10] [outer = (nil)] [url = http://example.com/browser/devtools/client/performance-new/test/browser/webchannel.html]
[task 2020-03-03T11:48:06.778Z] 11:48:06     INFO - GECKO(2493) | [Child 2672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fbf149c7400) [pid = 2672] [serial = 12] [outer = (nil)] [url = http://example.com/browser/devtools/client/performance-new/test/browser/webchannel.html]
[task 2020-03-03T11:48:06.878Z] 11:48:06     INFO - GECKO(2493) | [Child 2747: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f370b712400) [pid = 2747] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:48:07.144Z] 11:48:07     INFO - GECKO(2493) | [Child 2609: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7fa8c7e8d350) [pid = 2609] [serial = 4] [outer = (nil)] [url = about:newtab]
[task 2020-03-03T11:48:07.146Z] 11:48:07     INFO - GECKO(2493) | [Child 2609: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fa8adecf000 == 0 [pid = 2609] [id = {b0a8b31e-60e3-4f49-961c-398b0671968e}] [url = about:newtab]
[task 2020-03-03T11:48:07.146Z] 11:48:07     INFO - GECKO(2493) | [Child 2609: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7fa8ad50c000) [pid = 2609] [serial = 6] [outer = (nil)] [url = about:newtab]
[task 2020-03-03T11:48:08.959Z] 11:48:08     INFO - GECKO(2493) | [Child 2722: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f6f5788d6f0) [pid = 2722] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:48:08.974Z] 11:48:08     INFO - GECKO(2493) | [Child 2722: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f6f3cf1c400) [pid = 2722] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:48:10.076Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2769
[task 2020-03-03T11:48:10.178Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2873
[task 2020-03-03T11:48:10.254Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2918
[task 2020-03-03T11:48:10.330Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2672
[task 2020-03-03T11:48:10.345Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2794
[task 2020-03-03T11:48:10.401Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2747
[task 2020-03-03T11:48:10.422Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2722
[task 2020-03-03T11:48:10.490Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2609
[task 2020-03-03T11:48:10.766Z] 11:48:10     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2548
[task 2020-03-03T11:48:11.068Z] 11:48:11     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7f20d95abc00) [pid = 2493] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:48:14.003Z] 11:48:14     INFO - GECKO(2493) | [Parent 2493: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7f20cb437800) [pid = 2493] [serial = 27] [outer = (nil)] [url = about:blank]
[task 2020-03-03T11:48:14.534Z] 11:48:14     INFO - GECKO(2493) | Completed ShutdownLeaks collections in process 2493
[task 2020-03-03T11:48:14.535Z] 11:48:14     INFO - TEST-START | Shutdown
[task 2020-03-03T11:48:14.535Z] 11:48:14     INFO - Browser Chrome Test Summary
[task 2020-03-03T11:48:14.536Z] 11:48:14     INFO - Passed:  41
[task 2020-03-03T11:48:14.538Z] 11:48:14     INFO - Failed:  3
[task 2020-03-03T11:48:14.539Z] 11:48:14     INFO - Todo:    0
[task 2020-03-03T11:48:14.540Z] 11:48:14     INFO - Mode:    e10s
[task 2020-03-03T11:48:14.541Z] 11:48:14     INFO - *** End BrowserChrome Test Results ***

Greg: Can you please take a look at this bug?

Flags: needinfo?(gtatum)

Julien Cristau [:jcristau]

Updated

•

5 years ago

tracking-firefox75: ? → +

Greg Tatum [:gregtatum] (REO Fx132)

Assignee

Updated

•

5 years ago

Assignee: nobody → gtatum

Flags: needinfo?(gtatum)

Greg Tatum [:gregtatum] (REO Fx132)

Assignee

Comment 1

•

5 years ago

Try run on the upcoming patch: https://treeherder.mozilla.org/#/jobs?repo=try&revision=0144540f180d4b3698ff094d4507364d37985430

Greg Tatum [:gregtatum] (REO Fx132)

Assignee

Comment 2

•

5 years ago

Attached file Bug 1619578 - Make the profiler WebChannel work on beta; r?canaltinova — Details

The WebChannels were not being initialized on beta due to being initialized
after the feature pref was checked. This patch changes it so that
profiler.firefox.com will still have the WebChannel registered, and then
if the menu button is enabled via the WebChannel, the feature flag is flipped.

I did not include a test on this, as this is a temporary measure until we flip
on the mechanism for all users.

Greg Tatum [:gregtatum] (REO Fx132)

Assignee

Comment 3

•

5 years ago

I manually tested the patch doing a beta simulation as defined here, plus the try run above:

Oana Pop-Rus

Comment 4

•

5 years ago

Patch worked, no occurrence in today beta sims: https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&resultStatus=success%2Ctestfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel%2Crunnable&revision=fc85cf02318cc80fe646e0b8fabfde18bd7174ce&searchStr=linux%2C18.04%2Cx64%2Cdebug%2Cmochitests%2Ctest-linux1804-64%2Fdebug-mochitest-devtools-chrome-e10s-1%2Cm%28dt1%29&selectedJob=291621020

Pulsebot

Comment 5

•

5 years ago

Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/2e1a978b09d7 Make the profiler WebChannel work on beta; r=canaltinova

Cosmin Sabou [:CosminS]

Comment 6

•

5 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/2e1a978b09d7

Status: NEW → RESOLVED

Closed: 5 years ago

status-firefox75: affected → fixed

Resolution: --- → FIXED

Target Milestone: --- → Firefox 75

Andreea Pavel [:apavel]

Comment 7

•

5 years ago

Verified fixed in latest beta sims: https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed%2Cbusted%2Cexception%2Cusercancel%2Crunnable&revision=41a032346bbc2c91cd126d78ec746f956d54f01e&searchStr=devtools

Status: RESOLVED → VERIFIED

status-firefox75: fixed → verified

BMO Automation

Updated

•

5 years ago

Has Regression Range: --- → yes

Keywords: regression

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Perma beta TEST-UNEXPECTED-FAIL | devtools/client/performance-new/test/browser/browser_webchannel-enable-menu-button.js | Test timed out - when Gecko 75 merges to Beta on 2020-03-09

Categories

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

Tracking

(firefox-esr68 unaffected, firefox73 unaffected, firefox74 unaffected, firefox75+ verified)

People

(Reporter: dluca, Assigned: gregtatum)

References

(Regression)

Details

(Keywords: regression)

Crash Data

Security

(public)

User Story

Attachments

(1 file)

Description

Updated

Updated

Comment 1

Comment 2

Comment 3

Comment 4

Comment 5

Comment 6

Comment 7

Updated

Attachment

General

Description

File Name

Content Type