Closed Bug 1697425 Opened 4 years ago Closed 4 years ago

Intermittent devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js | Test timed out - followed by many more test failures

Categories

(DevTools :: Framework, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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


[task 2021-03-10T09:59:41.755Z] 09:59:41     INFO - TEST-START | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js
[task 2021-03-10T09:59:41.861Z] 09:59:41     INFO - GECKO(1529) | DevTools Server for Browser Toolbox listening on port: 44412
[task 2021-03-10T09:59:41.862Z] 09:59:41     INFO - GECKO(1529) | Starting Browser Toolbox /builds/worker/workspace/build/application/firefox/firefox-bin -no-remote -foreground -profile /tmp/tmpokesWs.mozrunner/chrome_debugger_profile -chrome chrome://devtools/content/framework/browser-toolbox/window.html
[task 2021-03-10T09:59:41.943Z] 09:59:41     INFO - GECKO(1529) | > ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpokesWs.mozrunner/runtests_leaks.log
[task 2021-03-10T09:59:41.944Z] 09:59:41     INFO - GECKO(1529) | > [1832, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-03-10T09:59:42.012Z] 09:59:42     INFO - GECKO(1529) | > [1832, Main Thread] WARNING: !ShouldProcessUpdates(): launching devtools: file /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4218
[task 2021-03-10T09:59:42.249Z] 09:59:42     INFO - GECKO(1529) | > 1615370382244	Marionette	INFO	Marionette enabled
[task 2021-03-10T09:59:42.252Z] 09:59:42     INFO - GECKO(1529) | > 1615370382245	Marionette	TRACE	Received observer notification profile-after-change
[task 2021-03-10T09:59:42.335Z] 09:59:42     INFO - GECKO(1529) | > 1615370382331	Marionette	TRACE	Received observer notification command-line-startup
[task 2021-03-10T09:59:42.397Z] 09:59:42     INFO - GECKO(1529) | > [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7ff9f5b91800 == 1 [pid = 1832] [id = 0]
[task 2021-03-10T09:59:42.400Z] 09:59:42     INFO - GECKO(1529) | > [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7ff9f5b82740) [pid = 1832] [serial = 1] [outer = (nil)]
[task 2021-03-10T09:59:42.401Z] 09:59:42     INFO - GECKO(1529) | [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7ff9f5b92400) [pid = 1832] [serial = 2] [outer = 0x7ff9f5b82740]
[task 2021-03-10T09:59:42.417Z] 09:59:42     INFO - GECKO(1529) | > 1615370382414	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-03-10T09:59:42.460Z] 09:59:42     INFO - GECKO(1529) | > [Parent 1832, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4255
[task 2021-03-10T09:59:42.501Z] 09:59:42     INFO - GECKO(1529) | > [Parent 1832, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1298
[task 2021-03-10T09:59:44.290Z] 09:59:44     INFO - GECKO(1529) | > [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7ff9f5b8d800 == 2 [pid = 1832] [id = 1]
[task 2021-03-10T09:59:44.290Z] 09:59:44     INFO - GECKO(1529) | [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7ff9f3a63ac0) [pid = 1832] [serial = 3] [outer = (nil)]
[task 2021-03-10T09:59:44.290Z] 09:59:44     INFO - GECKO(1529) | > [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7ff9f5cd3800) [pid = 1832] [serial = 4] [outer = 0x7ff9f3a63ac0]
[task 2021-03-10T09:59:44.326Z] 09:59:44     INFO - GECKO(1529) | > [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7ff9f125e400) [pid = 1832] [serial = 5] [outer = 0x7ff9f3a63ac0]
[task 2021-03-10T09:59:44.426Z] 09:59:44     INFO - GECKO(1529) | > [Parent 1832, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:3898
[task 2021-03-10T09:59:45.700Z] 09:59:45     INFO - GECKO(1529) | console.error: "Tried to send a 'getTarget' method reply on an already destroyed actor 'workerDescriptor'"
[task 2021-03-10T09:59:49.823Z] 09:59:49     INFO - GECKO(1529) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f8b8f88e800 == 0 [pid = 1718] [id = 0] [url = about:blank]
[task 2021-03-10T09:59:49.871Z] 09:59:49     INFO - GECKO(1529) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f8ba6063ac0) [pid = 1718] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2021-03-10T09:59:53.934Z] 09:59:53     INFO - GECKO(1529) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f8b8f890400) [pid = 1718] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2021-03-10T09:59:55.006Z] 09:59:55     INFO - GECKO(1529) | > [Parent 1832: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7ff9f5cd3800) [pid = 1832] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2021-03-10T10:00:25.924Z] 10:00:25     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7fcae7d633c0) [pid = 1610] [serial = 5] [outer = (nil)] [url = moz-extension://256eaff7-16d6-40cb-99fe-967f3fdae54c/_generated_background_page.html]
[task 2021-03-10T10:00:25.925Z] 10:00:25     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fcaede98400 == 4 [pid = 1610] [id = 2] [url = moz-extension://256eaff7-16d6-40cb-99fe-967f3fdae54c/_generated_background_page.html]
[task 2021-03-10T10:00:29.963Z] 10:00:29     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0x7fcae7374000) [pid = 1610] [serial = 13] [outer = (nil)] [url = moz-extension://256eaff7-16d6-40cb-99fe-967f3fdae54c/_generated_background_page.html]
[task 2021-03-10T10:00:29.964Z] 10:00:29     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0x7fcaede99400) [pid = 1610] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2021-03-10T10:00:29.964Z] 10:00:29     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0x7fcaede97800) [pid = 1610] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2021-03-10T10:00:29.964Z] 10:00:29     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7fcaedf06000) [pid = 1610] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2021-03-10T10:00:29.964Z] 10:00:29     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7fcaede94000) [pid = 1610] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2021-03-10T10:00:29.964Z] 10:00:29     INFO - GECKO(1529) | [Child 1610: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7fcae7da6000) [pid = 1610] [serial = 10] [outer = (nil)] [url = about:blank]
[task 2021-03-10T10:05:41.770Z] 10:05:41     INFO - TEST-INFO | started process screentopng
[task 2021-03-10T10:05:42.075Z] 10:05:42     INFO - TEST-INFO | screentopng: exit 0
[task 2021-03-10T10:05:42.076Z] 10:05:42     INFO - Buffered messages logged at 09:59:41
[task 2021-03-10T10:05:42.078Z] 10:05:42     INFO - Entering test bound runTest
[task 2021-03-10T10:05:42.078Z] 10:05:42     INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js | Browser toolbox started
[task 2021-03-10T10:05:42.079Z] 10:05:42     INFO -  - 
[task 2021-03-10T10:05:42.080Z] 10:05:42     INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js | Has session state - 
[task 2021-03-10T10:05:42.081Z] 10:05:42     INFO - Buffered messages logged at 10:01:11
[task 2021-03-10T10:05:42.081Z] 10:05:42     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 3
[task 2021-03-10T10:05:42.082Z] 10:05:42     INFO - Buffered messages logged at 10:02:41
[task 2021-03-10T10:05:42.082Z] 10:05:42     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 2
[task 2021-03-10T10:05:42.083Z] 10:05:42     INFO - Buffered messages logged at 10:04:11
[task 2021-03-10T10:05:42.083Z] 10:05:42     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2021-03-10T10:05:42.085Z] 10:05:42     INFO - Buffered messages finished
[task 2021-03-10T10:05:42.087Z] 10:05:42     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js | Test timed out - 
[task 2021-03-10T10:05:42.087Z] 10:05:42     INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-03-10T10:05:42.088Z] 10:05:42     INFO - GECKO(1529) | MEMORY STAT | vsize 2951MB | residentFast 423MB | heapAllocated 185MB
[task 2021-03-10T10:05:42.089Z] 10:05:42     INFO - TEST-OK | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js | took 360067ms
[task 2021-03-10T10:05:42.090Z] 10:05:42     INFO - GECKO(1529) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f8b897bb800 == 1 [pid = 1718] [id = 1]
[task 2021-03-10T10:05:42.091Z] 10:05:42     INFO - GECKO(1529) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f8ba6063900) [pid = 1718] [serial = 3] [outer = (nil)]
[task 2021-03-10T10:05:42.092Z] 10:05:42     INFO - GECKO(1529) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f8b897be800) [pid = 1718] [serial = 4] [outer = 0x7f8ba6063900]
[task 2021-03-10T10:05:42.093Z] 10:05:42     INFO - GECKO(1529) | JavaScript warning: resource://devtools/shared/builtin-modules.js, line 196: debuggee 'resource://devtools/shared/base-loader.js:289' would run
[task 2021-03-10T10:05:42.093Z] 10:05:42     INFO - checking window state```
Summary: Intermittent devtools/client/framework/browser-toolbox/test/<testName>r.js | Test timed out - → Intermittent devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_debugger.js | Test timed out - followed by many more test failures
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.