1615366 - Intermittent TEST-UNEXPECTED-TIMEOUT | devtools/client/framework/test/browser_toolbox_window_title_frame_select.js | application timed out after 370 seconds with no output

Status: RESOLVED INCOMPLETE

(DevTools :: Framework, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-02-13T14:39:25.866Z] 14:39:25 INFO - TEST-START | devtools/client/framework/test/browser_toolbox_window_title_frame_select.js
[task 2020-02-13T14:39:25.876Z] 14:39:25 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x10c9dd800 == 1 [pid = 1703] [id = {fce8b9ad-26a3-1240-a747-83f0fcf0a42a}]
[task 2020-02-13T14:39:25.876Z] 14:39:25 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x105954200) [pid = 1703] [serial = 223] [outer = 0x0]
[task 2020-02-13T14:39:25.876Z] 14:39:25 INFO - GECKO(1698) | [Child 1703, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 845
[task 2020-02-13T14:39:25.876Z] 14:39:25 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x10623e000) [pid = 1703] [serial = 224] [outer = 0x105954200]
[task 2020-02-13T14:39:25.963Z] 14:39:25 INFO - GECKO(1698) | [Child 1703, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-02-13T14:39:25.984Z] 14:39:25 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x11ee2f800) [pid = 1703] [serial = 225] [outer = 0x105954200]
[task 2020-02-13T14:39:26.031Z] 14:39:26 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x11efcc800 == 2 [pid = 1703] [id = {a894f7d3-abfe-7a43-960d-27f595726021}]
[task 2020-02-13T14:39:26.031Z] 14:39:26 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x11ef64880) [pid = 1703] [serial = 226] [outer = 0x0]
[task 2020-02-13T14:39:26.087Z] 14:39:26 INFO - GECKO(1698) | [Child 1703, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-02-13T14:39:26.088Z] 14:39:26 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x11ee37000) [pid = 1703] [serial = 227] [outer = 0x11ef64880]
[task 2020-02-13T14:39:26.179Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x11b68d800 == 15 [pid = 1698] [id = {560adee2-bfdf-8d46-bcbb-c2bac442fffd}]
[task 2020-02-13T14:39:26.180Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 60 (0x12683c3e0) [pid = 1698] [serial = 1138] [outer = 0x0]
[task 2020-02-13T14:39:26.180Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 61 (0x12ba5ec00) [pid = 1698] [serial = 1139] [outer = 0x12683c3e0]
[task 2020-02-13T14:39:26.221Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 62 (0x12807a000) [pid = 1698] [serial = 1140] [outer = 0x12683c3e0]
[task 2020-02-13T14:39:26.314Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsContentUtils.cpp, line 3784
[task 2020-02-13T14:39:26.484Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x119d60000 == 16 [pid = 1698] [id = {3778971d-fe15-d34f-bd7a-fad7da9b3871}]
[task 2020-02-13T14:39:26.484Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 63 (0x12a9eb3e0) [pid = 1698] [serial = 1141] [outer = 0x0]
[task 2020-02-13T14:39:26.485Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 64 (0x12bea2800) [pid = 1698] [serial = 1142] [outer = 0x12a9eb3e0]
[task 2020-02-13T14:39:26.947Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x128063000 == 17 [pid = 1698] [id = {5f7b0f9a-2976-1a41-a135-c0b3d0bad1ff}]
[task 2020-02-13T14:39:26.947Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 65 (0x119dbe020) [pid = 1698] [serial = 1143] [outer = 0x0]
[task 2020-02-13T14:39:26.949Z] 14:39:26 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 66 (0x157f9c000) [pid = 1698] [serial = 1144] [outer = 0x119dbe020]
[task 2020-02-13T14:39:27.005Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x159447800 == 18 [pid = 1698] [id = {c380d3f8-26af-9c4c-99c0-116b7d938341}]
[task 2020-02-13T14:39:27.005Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 67 (0x11bd66980) [pid = 1698] [serial = 1145] [outer = 0x0]
[task 2020-02-13T14:39:27.217Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 68 (0x155b56400) [pid = 1698] [serial = 1146] [outer = 0x11bd66980]
[task 2020-02-13T14:39:27.504Z] 14:39:27 INFO - GECKO(1698) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-02-13T14:39:27.504Z] 14:39:27 INFO - GECKO(1698) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2020-02-13T14:39:27.545Z] 14:39:27 INFO - GECKO(1698) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2020-02-13T14:39:27.545Z] 14:39:27 INFO - GECKO(1698) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2020-02-13T14:39:27.584Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x159721000 == 19 [pid = 1698] [id = {1e34abb7-5a88-3248-adcc-8a90a1bf3325}]
[task 2020-02-13T14:39:27.585Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 69 (0x1625a72e0) [pid = 1698] [serial = 1147] [outer = 0x0]
[task 2020-02-13T14:39:27.585Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 70 (0x159bdf400) [pid = 1698] [serial = 1148] [outer = 0x1625a72e0]
[task 2020-02-13T14:39:27.634Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x159c20800 == 20 [pid = 1698] [id = {c5fed119-8257-dc4a-b73c-6d42e7dcd400}]
[task 2020-02-13T14:39:27.634Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 71 (0x16dd5ec40) [pid = 1698] [serial = 1149] [outer = 0x0]
[task 2020-02-13T14:39:27.639Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 72 (0x159c34400) [pid = 1698] [serial = 1150] [outer = 0x16dd5ec40]
[task 2020-02-13T14:39:27.772Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 73 (0x127778000) [pid = 1698] [serial = 1151] [outer = 0x16dd5ec40]
[task 2020-02-13T14:39:27.789Z] 14:39:27 INFO - GECKO(1698) | [Parent 1698, Main Thread] WARNING: '!inner', file /builds/worker/workspace/build/src/dom/ipc/JSWindowActorService.cpp, line 182
[task 2020-02-13T14:39:39.731Z] 14:39:39 INFO - GECKO(1698) | [Child 1702: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x11f4ea000 == 1 [pid = 1702] [id = {e7757ade-3cf2-8445-bccf-78b2be4329d4}] [url = http://example.com/browser/devtools/client/framework/test/browser_toolbox_window_title_changes_page.html]
[task 2020-02-13T14:39:40.899Z] 14:39:40 INFO - GECKO(1698) | [Child 1702: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x10eed8980) [pid = 1702] [serial = 195] [outer = 0x0] [url = http://example.com/browser/devtools/client/framework/test/browser_toolbox_window_title_changes_page.html]
[task 2020-02-13T14:39:48.368Z] 14:39:48 INFO - GECKO(1698) | [Child 1701: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x10b548800 == 0 [pid = 1701] [id = {c03f9922-756a-274b-9177-d78838d7938f}] [url = about:blank]
[task 2020-02-13T14:39:57.213Z] 14:39:57 INFO - GECKO(1698) | [Child 1702: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x11f521400) [pid = 1702] [serial = 201] [outer = 0x0] [url = about:blank]
[task 2020-02-13T14:39:57.213Z] 14:39:57 INFO - GECKO(1698) | [Child 1702: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x14f2e2c00) [pid = 1702] [serial = 198] [outer = 0x0] [url = data:text/plain;charset=UTF-8,12345]
[task 2020-02-13T14:39:57.214Z] 14:39:57 INFO - GECKO(1698) | [Child 1702: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x14adae800) [pid = 1702] [serial = 197] [outer = 0x0] [url = data:text/plain;charset=UTF-8,abcde]
[task 2020-02-13T14:39:57.214Z] 14:39:57 INFO - GECKO(1698) | [Child 1702: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x14f33b800) [pid = 1702] [serial = 199] [outer = 0x0] [url = http://example.com/browser/devtools/client/framework/test/browser_toolbox_window_title_changes_page.html]
[task 2020-02-13T14:40:06.359Z] 14:40:06 INFO - GECKO(1698) | [Child 1701: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x10be13400) [pid = 1701] [serial = 177] [outer = 0x0] [url = about:blank]
[task 2020-02-13T14:40:07.770Z] 14:40:07 INFO - GECKO(1698) | [Child 1701: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x112394200) [pid = 1701] [serial = 176] [outer = 0x0] [url = about:blank]
[task 2020-02-13T14:40:23.516Z] 14:40:23 INFO - GECKO(1698) | [Child 1701: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x112314000) [pid = 1701] [serial = 178] [outer = 0x0] [url = about:blank]
[task 2020-02-13T14:40:43.780Z] 14:40:43 INFO - GECKO(1698) | [Child 1703: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x10623e000) [pid = 1703] [serial = 224] [outer = 0x0] [url = about:blank]
[task 2020-02-13T14:46:53.815Z] 14:46:53 INFO - Buffered messages logged at 14:39:25
[task 2020-02-13T14:46:53.815Z] 14:46:53 INFO - Entering test bound
[task 2020-02-13T14:46:53.816Z] 14:46:53 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/framework/test/browser_toolbox_window_title_frame_select_page.html
[task 2020-02-13T14:46:53.816Z] 14:46:53 INFO - Buffered messages logged at 14:39:26
[task 2020-02-13T14:46:53.816Z] 14:46:53 INFO - Tab added and finished loading
[task 2020-02-13T14:46:53.816Z] 14:46:53 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 2020-02-13T14:46:53.816Z] 14:46:53 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 2020-02-13T14:46:53.816Z] 14:46:53 INFO - Buffered messages logged at 14:39:27
[task 2020-02-13T14:46:53.817Z] 14:46:53 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 2020-02-13T14:46:53.817Z] 14:46:53 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 2020-02-13T14:46:53.817Z] 14:46:53 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 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Console message: OpenGL compositor Initialized Succesfully.
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Version: 2.1 INTEL-12.9.22
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Vendor: Intel Inc.
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Renderer: Intel Iris OpenGL Engine
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - FBO Texture Target: TEXTURE_2D
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Console message: OpenGL compositor Initialized Succesfully.
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Version: 2.1 INTEL-12.9.22
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Vendor: Intel Inc.
[task 2020-02-13T14:46:53.817Z] 14:46:53 INFO - Renderer: Intel Iris OpenGL Engine
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - FBO Texture Target: TEXTURE_2D
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - Buffered messages finished
[task 2020-02-13T14:46:53.818Z] 14:46:53 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/framework/test/browser_toolbox_window_title_frame_select.js | application timed out after 370 seconds with no output
[task 2020-02-13T14:46:53.818Z] 14:46:53 ERROR - Force-terminating active process(es).
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - Determining child pids from psutil...
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - [1707, 1700, 1706, 1699, 1701, 1702, 1703, 1704, 1705]
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - ==> process 1698 launched child process 1699
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - ==> process 1698 launched child process 1700
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - ==> process 1698 launched child process 1701
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - ==> process 1698 launched child process 1702
[task 2020-02-13T14:46:53.818Z] 14:46:53 INFO - ==> process 1698 launched child process 1703
[task 2020-02-13T14:46:53.819Z] 14:46:53 INFO - ==> process 1698 launched child process 1704
[task 2020-02-13T14:46:53.819Z] 14:46:53 INFO - ==> process 1698 launched child process 1705
[task 2020-02-13T14:46:53.819Z] 14:46:53 INFO - ==> process 1698 launched child process 1706
[task 2020-02-13T14:46:53.819Z] 14:46:53 INFO - ==> process 1698 launched child process 1707
[task 2020-02-13T14:46:53.819Z] 14:46:53 INFO - Found child pids: set([1699, 1700, 1701, 1702, 1703, 1704, 1705, 1706, 1707])
[task 2020-02-13T14:46:53.819Z] 14:46:53 INFO - Killing process: 1699
[task 2020-02-13T14:46:53.819Z] 14:46:53 INFO - TEST-INFO | started process screencapture
[task 2020-02-13T14:46:53.942Z] 14:46:53 INFO - TEST-INFO | screencapture: exit 0

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• macosx1014-64: 1

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

Comment 2

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

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