1656227 - Intermittent devtools/client/framework/test/browser_toolbox_window_title_frame_select.js | Found an unexpected devtools:toolbox at the end of test run -

Status: RESOLVED INCOMPLETE

(DevTools :: Framework, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-07-30T11:53:16.243Z] 11:53:16     INFO - TEST-START | devtools/client/framework/test/browser_toolbox_window_title_frame_select.js
[task 2020-07-30T11:53:16.698Z] 11:53:16     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2020-07-30T11:53:16.739Z] 11:53:16     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-07-30T11:53:16.754Z] 11:53:16     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2020-07-30T11:53:16.769Z] 11:53:16     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-07-30T11:53:16.769Z] 11:53:16     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2020-07-30T11:53:16.769Z] 11:53:16     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2020-07-30T11:53:17.029Z] 11:53:17     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-07-30T11:53:17.029Z] 11:53:17     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2020-07-30T11:53:17.036Z] 11:53:17     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2020-07-30T11:53:20.331Z] 11:53:20     INFO - GECKO(3180) | Handler function executor threw an exception: Error: Must use a native JavaScript stack frame
[task 2020-07-30T11:53:20.331Z] 11:53:20     INFO - GECKO(3180) | Stack: callFunctionWithAsyncStack@resource://devtools/shared/platform/stack.js:61:13
[task 2020-07-30T11:53:20.331Z] 11:53:20     INFO - GECKO(3180) | executor@resource://devtools/shared/DevToolsUtils.js:49:35
[task 2020-07-30T11:53:20.331Z] 11:53:20     INFO - GECKO(3180) | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | Line: 61, column: 13
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | console.error: (new Error("Must use a native JavaScript stack frame", "resource://devtools/shared/platform/stack.js", 61))
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | Handler function executor threw an exception: Error: Must use a native JavaScript stack frame
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | Stack: callFunctionWithAsyncStack@resource://devtools/shared/platform/stack.js:61:13
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | executor@resource://devtools/shared/DevToolsUtils.js:49:35
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | Line: 61, column: 13
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | console.error: (new Error("Must use a native JavaScript stack frame", "resource://devtools/shared/platform/stack.js", 61))
[task 2020-07-30T11:53:20.332Z] 11:53:20     INFO - GECKO(3180) | Handler function executor threw an exception: Error: Must use a native JavaScript stack frame
[task 2020-07-30T11:53:20.333Z] 11:53:20     INFO - GECKO(3180) | Stack: callFunctionWithAsyncStack@resource://devtools/shared/platform/stack.js:61:13
[task 2020-07-30T11:53:20.333Z] 11:53:20     INFO - GECKO(3180) | executor@resource://devtools/shared/DevToolsUtils.js:49:35
[task 2020-07-30T11:53:20.333Z] 11:53:20     INFO - GECKO(3180) | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2020-07-30T11:53:20.333Z] 11:53:20     INFO - GECKO(3180) | Line: 61, column: 13
[task 2020-07-30T11:53:20.333Z] 11:53:20     INFO - GECKO(3180) | console.error: (new Error("Must use a native JavaScript stack frame", "resource://devtools/shared/platform/stack.js", 61))
[task 2020-07-30T11:53:20.388Z] 11:53:20     INFO - GECKO(3180) | MEMORY STAT | vsize 1798MB | vsizeMaxContiguous 59MB | residentFast 928MB | heapAllocated 432MB
[task 2020-07-30T11:53:20.388Z] 11:53:20     INFO - TEST-OK | devtools/client/framework/test/browser_toolbox_window_title_frame_select.js | took 4140ms
[task 2020-07-30T11:53:20.390Z] 11:53:20     INFO - checking window state
[task 2020-07-30T11:53:20.392Z] 11:53:20     INFO - TEST-INFO | started process screenshot
[task 2020-07-30T11:53:20.491Z] 11:53:20     INFO - TEST-INFO | screenshot: exit 0
[task 2020-07-30T11:53:20.492Z] 11:53:20     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_window_title_frame_select.js | Found an unexpected devtools:toolbox at the end of test run - 
[task 2020-07-30T11:53:20.492Z] 11:53:20     INFO - GECKO(3180) | must wait for focus
[task 2020-07-30T11:53:20.492Z] 11:53:20     INFO - TEST-START | devtools/client/framework/test/browser_toolbox_zoom.js
[task 2020-07-30T11:53:23.971Z] 11:53:23     INFO - GECKO(3180) | Handler function executor threw an exception: Error: Must use a native JavaScript stack frame
[task 2020-07-30T11:53:23.971Z] 11:53:23     INFO - GECKO(3180) | Stack: callFunctionWithAsyncStack@resource://devtools/shared/platform/stack.js:61:13
[task 2020-07-30T11:53:23.971Z] 11:53:23     INFO - GECKO(3180) | executor@resource://devtools/shared/DevToolsUtils.js:49:35
[task 2020-07-30T11:53:23.971Z] 11:53:23     INFO - GECKO(3180) | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2020-07-30T11:53:23.972Z] 11:53:23     INFO - GECKO(3180) | Line: 61, column: 13
[task 2020-07-30T11:53:23.972Z] 11:53:23     INFO - GECKO(3180) | console.error: (new Error("Must use a native JavaScript stack frame", "resource://devtools/shared/platform/stack.js", 61))
[task 2020-07-30T11:53:24.019Z] 11:53:24     INFO - GECKO(3180) | MEMORY STAT | vsize 1794MB | vsizeMaxContiguous 53MB | residentFast 925MB | heapAllocated 430MB
[task 2020-07-30T11:53:24.019Z] 11:53:24     INFO - TEST-OK | devtools/client/framework/test/browser_toolbox_zoom.js | took 3584ms
[task 2020-07-30T11:53:24.034Z] 11:53:24     INFO - checking window state
[task 2020-07-30T11:53:24.049Z] 11:53:24     INFO - TEST-START | devtools/client/framework/test/browser_toolbox_zoom_popup.js
[task 2020-07-30T11:53:24.549Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2020-07-30T11:53:24.556Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-07-30T11:53:24.611Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2020-07-30T11:53:24.611Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2020-07-30T11:53:24.618Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2020-07-30T11:53:24.638Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-07-30T11:53:24.665Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-07-30T11:53:24.668Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2020-07-30T11:53:24.675Z] 11:53:24     INFO - GECKO(3180) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2020-07-30T11:53:28.115Z] 11:53:28     INFO - GECKO(3180) | Handler function executor threw an exception: Error: Must use a native JavaScript stack frame
[task 2020-07-30T11:53:28.115Z] 11:53:28     INFO - GECKO(3180) | Stack: callFunctionWithAsyncStack@resource://devtools/shared/platform/stack.js:61:13
[task 2020-07-30T11:53:28.115Z] 11:53:28     INFO - GECKO(3180) | executor@resource://devtools/shared/DevToolsUtils.js:49:35
[task 2020-07-30T11:53:28.115Z] 11:53:28     INFO - GECKO(3180) | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2020-07-30T11:53:28.116Z] 11:53:28     INFO - GECKO(3180) | Line: 61, column: 13
[task 2020-07-30T11:53:28.116Z] 11:53:28     INFO - GECKO(3180) | console.error: (new Error("Must use a native JavaScript stack frame", "resource://devtools/shared/platform/stack.js", 61))
[task 2020-07-30T11:53:28.171Z] 11:53:28     INFO - GECKO(3180) | MEMORY STAT | vsize 1807MB | vsizeMaxContiguous 63MB | residentFast 946MB | heapAllocated 445MB
[task 2020-07-30T11:53:28.171Z] 11:53:28     INFO - TEST-OK | devtools/client/framework/test/browser_toolbox_zoom_popup.js | took 4120ms
[task 2020-07-30T11:53:28.186Z] 11:53:28     INFO - checking window state
[task 2020-07-30T11:53:28.186Z] 11:53:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-30T11:53:28.187Z] 11:53:28     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_zoom_popup.js | Found an unexpected devtools:toolbox at the end of test run - 
[task 2020-07-30T11:53:28.187Z] 11:53:28     INFO - GECKO(3180) | must wait for focus
[task 2020-07-30T11:53:28.223Z] 11:53:28     INFO - TEST-START | devtools/client/framework/test/browser_two_tabs.js
[task 2020-07-30T11:53:28.370Z] 11:53:28     INFO - GECKO(3180) | console.error: "Error while calling actor 'root's method 'getTab'" "Unable to find tab with tabId '-999'"
[task 2020-07-30T11:53:28.370Z] 11:53:28     INFO - GECKO(3180) | console.error: "Error while calling actor 'root's method 'getTab'" "Unable to find tab with outerWindowID '-999'"
[task 2020-07-30T11:53:28.457Z] 11:53:28     INFO - GECKO(3180) | MEMORY STAT | vsize 1808MB | vsizeMaxContiguous 63MB | residentFast 946MB | heapAllocated 440MB
[task 2020-07-30T11:53:28.457Z] 11:53:28     INFO - TEST-OK | devtools/client/framework/test/browser_two_tabs.js | took 231ms
[task 2020-07-30T11:53:28.461Z] 11:53:28     INFO - checking window state
[task 2020-07-30T11:53:28.481Z] 11:53:28     INFO - GECKO(3180) | Completed ShutdownLeaks collections in process 1188
[task 2020-07-30T11:53:28.484Z] 11:53:28     INFO - TEST-START | Shutdown

Comment 1

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

From bug 1654745?

Comment 2

[Intermittent Failures Robot]

7 failures in 4289 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2
• autoland: 5

Platform breakdown:

• windows7-32-shippable: 2
• windows7-32: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1656227&startday=2020-07-27&endday=2020-08-02&tree=all

Comment 3

[Julian Descottes [:jdescottes]]

From bug 1654745?

Most likely, yes.

So far we have three intermittents logged for "Found an unexpected devtools:toolbox at the end of test run":

• browser_toolbox_window_title_frame_select.js this bug
• browser_toolbox_window_title_changes.js in Bug 1656184
• browser_rules_eyedropper.js in Bug 1656097

All three tests share one thing in common: they switch to window host.
Also note that all failures occur on Windows 7 32 bits for now.

The failure screenshots show that the host has not been closed, the devtools window is indeed still opened:
https://firefoxci.taskcluster-artifacts.net/FlHWFzOpSb6J2qfSGHocwg/0/public/test_info/mozilla-test-fail-screenshot_hxxqdl.png

Haven't been able to reproduce locally so far.

Comment 4

[Intermittent Failures Robot]

5 failures in 4618 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 1

Platform breakdown:

• windows7-32: 4
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1656227&startday=2020-08-03&endday=2020-08-09&tree=all

Comment 5

[Intermittent Failures Robot]

3 failures in 3601 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-central: 2

Platform breakdown:

• windows7-32: 1
• windows7-32-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1656227&startday=2020-08-10&endday=2020-08-16&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 4100 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1656227&startday=2020-08-17&endday=2020-08-23&tree=all

Comment 7

[Intermittent Failures Robot]

2 failures in 4046 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows7-32-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1656227&startday=2020-08-24&endday=2020-08-30&tree=all

Comment 8

[Intermittent Failures Robot]

2 failures in 2984 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

• windows7-32-shippable: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1656227&startday=2020-08-31&endday=2020-09-06&tree=all

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

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