Closed Bug 1784544 Opened 3 years ago Closed 3 years ago

Intermittent TV [tier 2] TEST-UNEXPECTED-TIMEOUT | devtools/client/framework/test/browser_toolbox_screenshot_tool.js | single tracking bug

Categories

(DevTools :: Framework, defect, P5)

Product:
DevTools
Component:
Framework
Type:
defect

Tracking

(firefox-esr91 unaffected, firefox-esr102 unaffected, firefox103 unaffected, firefox104 unaffected, firefox105 wontfix, firefox106 wontfix)

Status:
RESOLVED INCOMPLETE
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox-esr102 --- unaffected
firefox103 --- unaffected
firefox104 --- unaffected
firefox105 --- wontfix
firefox106 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: mlaza [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=387184178&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IvOeLfq4RdWTbbgWWHsTkw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IvOeLfq4RdWTbbgWWHsTkw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2022-08-12T18:24:56.979Z] 18:24:56     INFO - TEST-START | devtools/client/framework/test/browser_toolbox_screenshot_tool.js
[task 2022-08-12T18:25:21.088Z] 18:25:21     INFO - GECKO(1624) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2022-08-12T18:25:21.218Z] 18:25:21     INFO - GECKO(1624) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2022-08-12T18:25:21.497Z] 18:25:21     INFO - GECKO(1624) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2022-08-12T18:25:21.499Z] 18:25:21     INFO - GECKO(1624) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2022-08-12T18:31:31.574Z] 18:31:31     INFO - Buffered messages logged at 18:24:57
[task 2022-08-12T18:31:31.575Z] 18:31:31     INFO - Entering test bound 
[task 2022-08-12T18:31:31.575Z] 18:31:31     INFO - Buffered messages logged at 18:24:58
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO - Adding a new tab with URL: https://example.org/document-builder.sjs?html=
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO -   <style>
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO -     body {
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO -       margin: 0;
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO -       height: 10001px;
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO -     }
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO -     iframe {
[task 2022-08-12T18:31:31.576Z] 18:31:31     INFO -       height: 50px;
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -       border:none;
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -       display: block;
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -     }
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -   </style>
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -   <iframe
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -     src="https://example.org/document-builder.sjs?html=<body style='margin:0;height:30px;background:rgb(255,0,0)'></body>"
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -     id="same-origin"></iframe>
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -   <iframe
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -     src="https://example.com/document-builder.sjs?html=<body style='margin:0;height:30px;background:rgb(0,255,0)'></body>"
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO -     id="remote"></iframe>
[task 2022-08-12T18:31:31.577Z] 18:31:31     INFO - Buffered messages logged at 18:25:00
[task 2022-08-12T18:31:31.578Z] 18:31:31     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "https://example.org/document-builder.sjs?html=%20%20%3Cstyle%3E%20%20%20%20body%20{%20%20%20%20%20%20margin:%200;%20%20%20%20%20%20height:%2010001px;%20%20%20%20}%20%20%20%20iframe%20{%20%20%20%20%20%20height:%2050px;%20%20%20%20%20%20border:none;%20%20%20%20%20%20display:%20block;%20%20%20%20}%20%20%3C/style%3E%20%20%3Ciframe%20%20%20%20src=%22https://example.org/document-builder.sjs?html=%3Cbody%20style=%27margin:0;height:30px;background:rgb(255,0,0)%27%3E%3C/body%3E%22%20%20%20%20id=%22same-origin%22%3E%" line: 0}]
[task 2022-08-12T18:31:31.579Z] 18:31:31     INFO - Buffered messages logged at 18:25:02
[task 2022-08-12T18:31:31.579Z] 18:31:31     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "https://example.org/document-builder.sjs?html=%3Cbody%20style=%27margin:0;height:30px;background:rgb(255,0,0)%27%3E%3C/body%3E" line: 0}]
[task 2022-08-12T18:31:31.580Z] 18:31:31     INFO - Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "https://example.org/document-builder.sjs?html=%3Cbody%20style=%27margin:0;height:30px;background:rgb(255,0,0)%27%3E%3C/body%3E" line: 0}]
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - Console message: [JavaScript Error: "Unknown Collection "main/partitioning-exempt-urls"" {file: "resource://services-settings/RemoteSettingsClient.jsm" line: 192}]
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - UnknownCollectionError@resource://services-settings/RemoteSettingsClient.jsm:192:5
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - sync@resource://services-settings/RemoteSettingsClient.jsm:588:13
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - async*get/this._importingPromise<@resource://services-settings/RemoteSettingsClient.jsm:438:26
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - async*get@resource://services-settings/RemoteSettingsClient.jsm:444:13
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - async*lazyInit@resource://gre/modules/PartitioningExceptionListService.jsm:111:26
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - registerAndRunExceptionListObserver@resource://gre/modules/PartitioningExceptionListService.jsm:135:10
[task 2022-08-12T18:31:31.581Z] 18:31:31     INFO - 
[task 2022-08-12T18:31:31.582Z] 18:31:31     INFO - Buffered messages logged at 18:25:03
[task 2022-08-12T18:31:31.582Z] 18:31:31     INFO - Console message: [JavaScript Warning: "Partitioned cookie or storage access was provided to “https://example.com/document-builder.sjs?html=%3Cbody%20style=%27margin:0;height:30px;background:rgb(0,255,0)%27%3E%3C/body%3E” because it is loaded in the third-party context and dynamic state partitioning is enabled."]
[task 2022-08-12T18:31:31.583Z] 18:31:31     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "https://example.com/document-builder.sjs?html=%3Cbody%20style=%27margin:0;height:30px;background:rgb(0,255,0)%27%3E%3C/body%3E" line: 0}]
[task 2022-08-12T18:31:31.583Z] 18:31:31     INFO - Tab added and finished loading
[task 2022-08-12T18:31:31.584Z] 18:31:31     INFO - Open the toolbox
[task 2022-08-12T18:31:31.584Z] 18:31:31     INFO - Buffered messages logged at 18:25:10[task 2022-08-12T18:31:31.585Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.585Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.586Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 62}]
[task 2022-08-12T18:31:31.586Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.587Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.587Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 62}]
[task 2022-08-12T18:31:31.588Z] 18:31:31     INFO - Buffered messages logged at 18:25:17
[task 2022-08-12T18:31:31.588Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.589Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.589Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 62}]
[task 2022-08-12T18:31:31.590Z] 18:31:31     INFO - Buffered messages logged at 18:25:19
[task 2022-08-12T18:31:31.590Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.591Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.591Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 62}]
[task 2022-08-12T18:31:31.592Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.592Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-08-12T18:31:31.593Z] 18:31:31     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 62}]
[task 2022-08-12T18:31:31.593Z] 18:31:31     INFO - Buffered messages logged at 18:25:20
[task 2022-08-12T18:31:31.594Z] 18:31:31     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 251}]
[task 2022-08-12T18:31:31.594Z] 18:31:31     INFO - Buffered messages finished
[task 2022-08-12T18:31:31.595Z] 18:31:31    ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/framework/test/browser_toolbox_screenshot_tool.js | application timed out after 370 seconds with no output
[task 2022-08-12T18:31:31.595Z] 18:31:31    ERROR - Force-terminating active process(es).
[task 2022-08-12T18:31:31.595Z] 18:31:31     INFO - Determining child pids from psutil...
[task 2022-08-12T18:31:31.596Z] 18:31:31     INFO - [1632, 1637, 1647, 1648, 1659, 1682]
[task 2022-08-12T18:31:31.596Z] 18:31:31     INFO - ==> process 1624 launched child process 1632
[task 2022-08-12T18:31:31.596Z] 18:31:31     INFO - ==> process 1624 launched child process 1637
[task 2022-08-12T18:31:31.597Z] 18:31:31     INFO - ==> process 1624 launched child process 1647
[task 2022-08-12T18:31:31.597Z] 18:31:31     INFO - ==> process 1624 launched child process 1648
[task 2022-08-12T18:31:31.597Z] 18:31:31     INFO - ==> process 1624 launched child process 1649
[task 2022-08-12T18:31:31.597Z] 18:31:31     INFO - ==> process 1624 launched child process 1654
[task 2022-08-12T18:31:31.598Z] 18:31:31     INFO - ==> process 1624 launched child process 1659
[task 2022-08-12T18:31:31.598Z] 18:31:31     INFO - ==> process 1624 launched child process 1682
[task 2022-08-12T18:31:31.599Z] 18:31:31     INFO - Found child pids: {1632, 1637, 1647, 1648, 1649, 1682, 1654, 1659}
[task 2022-08-12T18:31:31.599Z] 18:31:31     INFO - Failed to get child procs
[task 2022-08-12T18:31:31.599Z] 18:31:31     INFO - Killing process: 1632
[task 2022-08-12T18:31:31.599Z] 18:31:31     INFO - TEST-INFO | started process screencapture
[task 2022-08-12T18:31:31.705Z] 18:31:31     INFO - TEST-INFO | screencapture: exit 0

:nchevobbe, since you are the author of the regressor, bug 1783373, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(nchevobbe)

Set release status flags based on info from the regressing bug 1783373

status-firefox103: --- → unaffected
status-firefox104: --- → unaffected
status-firefox105: --- → affected
status-firefox-esr102: --- → unaffected
status-firefox-esr91: --- → unaffected

we seem to wait in https://searchfox.org/mozilla-central/rev/9cd1e8cabf67ef5a47e95d70b7f40c9d3ad02ad0/testing/mochitest/browser-test.js#570-573 , but this is on the test harness, so not sure what's happening.

There's not a lot of failure at the moment, let's see how this evolve

Flags: needinfo?(nchevobbe)

Set release status flags based on info from the regressing bug 1783373

status-firefox106: --- → affected

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

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.