Closed Bug 1604751 Opened 5 years ago Closed 2 years ago

Intermittent devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out -

Categories

(DevTools :: Framework, defect, P5)

defect

Tracking

(Fission Milestone:Future, firefox92 affected)

RESOLVED DUPLICATE of bug 1778471
Fission Milestone Future
Tracking Status
firefox92 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned, NeedInfo)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: dt-fission-future [stockwell unknown])

Attachments

(1 file)

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=281705592&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FJlOQw3hReCAx_QewMOeNQ/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/FJlOQw3hReCAx_QewMOeNQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2019-12-18T09:01:11.852Z] 09:01:11 INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Has session state -
[task 2019-12-18T09:01:11.852Z] 09:01:11 INFO - Buffered messages logged at 08:55:20
[task 2019-12-18T09:01:11.852Z] 09:01:11 INFO - Console message: [JavaScript Warning: "DebuggeeWouldRun: debuggee resource://devtools/shared/base-loader.js:373' would run" {file: "resource://devtools/shared/builtin-modules.js" line: 221}] [task 2019-12-18T09:01:11.852Z] 09:01:11 INFO - Console message: [JavaScript Warning: "DebuggeeWouldRun: debuggeeresource://devtools/shared/base-loader.js:373' would run" {file: "resource://devtools/shared/builtin-modules.js" line: 221}]
[task 2019-12-18T09:01:11.853Z] 09:01:11 INFO - Buffered messages logged at 08:55:26
[task 2019-12-18T09:01:11.855Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.855Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.855Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.855Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.855Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.856Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.859Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.859Z] 09:01:11 INFO - Console message: [JavaScript Error: "Error: Failed to fetch moz-extension://44fb2e8a-01ce-c54d-b4b9-aff9b9e7d45f/build/buildSettings.js. Code 2152857618." {file: "resource://devtools/shared/DevToolsUtils.js" line: 568}]
[task 2019-12-18T09:01:11.860Z] 09:01:11 INFO - Console message: [JavaScript Error: "Error: Failed to fetch moz-extension://44fb2e8a-01ce-c54d-b4b9-aff9b9e7d45f/background/startBackground.js. Code 2152857618." {file: "resource://devtools/shared/DevToolsUtils.js" line: 568}]
[task 2019-12-18T09:01:11.860Z] 09:01:11 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-12-18T09:01:11.860Z] 09:01:11 INFO - Buffered messages logged at 08:56:41
[task 2019-12-18T09:01:11.860Z] 09:01:11 INFO - Longer timeout required, waiting longer... Remaining timeouts: 3
[task 2019-12-18T09:01:11.860Z] 09:01:11 INFO - Buffered messages logged at 08:58:11
[task 2019-12-18T09:01:11.860Z] 09:01:11 INFO - Longer timeout required, waiting longer... Remaining timeouts: 2
[task 2019-12-18T09:01:11.861Z] 09:01:11 INFO - Buffered messages logged at 08:59:41
[task 2019-12-18T09:01:11.861Z] 09:01:11 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2019-12-18T09:01:11.861Z] 09:01:11 INFO - Buffered messages finished
[task 2019-12-18T09:01:11.861Z] 09:01:11 INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out -
[task 2019-12-18T09:01:11.861Z] 09:01:11 INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | The main process DebuggerServer has no pending connection when the test ends -

Tracking DevTools tests for Fission Nightly (M6)

Fission Milestone: --- → M6
Regressed by: 1603209
Summary: Intermittent [TV] devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out - → Intermittent devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out -
Summary: Intermittent devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out - → Perma Tier 2 Central devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out -
Regressed by: 1588050
No longer regressed by: 1603209
Summary: Perma Tier 2 Central devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out - → Perma tier2 ccov devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out -
Has Regression Range: --- → yes
Flags: needinfo?(btara)

I see some exception coming from the debugger, I'm wondering if they can make this test to fail?

[task 2020-01-06T11:32:16.976Z] 11:32:16     INFO - GECKO(10624) | console.warn: "Error while detaching the worker target front:" (new Error("Protocol error (noSuchActor): No such actor for ID: server2.conn0.workerTarget41", "resource://devtools/shared/protocol/Front.js", 276))
[task 2020-01-06T11:32:17.047Z] 11:32:17     INFO - GECKO(10624) | console.log: "getSources failed. Connection may have closed: Error: Can not send request 'sources' because front 'thread' is already destroyed."
[task 2020-01-06T11:32:18.839Z] 11:32:18     INFO - GECKO(10624) | console.error: "Exception when calling onAvailable handler" "threadFront is null" (new TypeError("threadFront is null", "resource://devtools/client/debugger/src/client/firefox/commands.js", 436))

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=283594540&repo=mozilla-central&lineNumber=1730

Here is an attempt to work around this exception, the threadFront is null one:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=70173db042a689bfe9a03a5ab294bb6b150b2190

Summary: Perma tier2 ccov devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out - → Intermittent devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out -

This browser_browser_toolbox_fission_contentframe_inspector.js test is still failing intermittently with Fission.

I'm deferring this bug to Fission M7 Beta because the failure rate is very low (only about 1-3 failures per week).

Fission Milestone: M6 → M7

Adding dt-fission whiteboard tag to DevTools bugs that mention Fission or block Fission meta bugs but don't already have a dt-fission whiteboard tag.Adding dt-fission whiteboard tag to DevTools bugs that mention Fission or block Fission meta bugs but don't already have a dt-fission whiteboard tag.

Whiteboard: dt-fission

Bulk change of all bugs with whiteboard tag of dt-fission to Fission MVP milestone.

Fission Milestone: M7 → MVP

Moving old "dt-fission" bugs to "dt-fission-future" because they don't block Fission MVP.

Fission Milestone: MVP → Future
Whiteboard: dt-fission → dt-fission-future

There have been 42 total failures in the last 7 days (recent failure log).
Affected platforms are:

  • windows10-64-ccov-qr
  • linux1804-64-qr debug
Whiteboard: dt-fission-future → dt-fission-future [stockwell needswork:owner]

These browser_browser_toolbox_fission_contentframe_inspector.js test failures are not new, but they appear to have increased starting in mid-July.

Update:
There have been 47 failures within the last 7 days:
• 45 failures on Linux 18.04 x64 WebRender debug
• 2 failures on Windows 10 x64 CCov WebRender opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=348445666&repo=mozilla-central&lineNumber=3037

[task 2021-08-14T21:37:24.765Z] 21:37:24     INFO - TEST-START | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js
[task 2021-08-14T21:37:24.826Z] 21:37:24     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-14T21:37:24.934Z] 21:37:24     INFO - GECKO(1539) | [Child 1987: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fb877012c00 == 0 [pid = 1987] [id = 0] [url = http://example.com/document-builder.sjs?html=%3Chtml%3E%3Ctitle%3ETest%20tab%3C/title%3E%3C/html%3E]
[task 2021-08-14T21:37:25.027Z] 21:37:25     INFO - GECKO(1539) | DevTools Server for Browser Toolbox listening on port: 41272
[task 2021-08-14T21:37:25.035Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-14T21:37:25.038Z] 21:37:25     INFO - GECKO(1539) | Starting Browser Toolbox /builds/worker/workspace/build/application/firefox/firefox-bin -no-remote -foreground -profile /tmp/tmpllde8ow2.mozrunner/chrome_debugger_profile -chrome chrome://devtools/content/framework/browser-toolbox/window.html
[task 2021-08-14T21:37:25.057Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-14T21:37:25.064Z] 21:37:25     INFO - GECKO(1539) | [Child 1987: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fb890f64580) [pid = 1987] [serial = 1] [outer = 0] [url = http://example.com/document-builder.sjs?html=%3Chtml%3E%3Ctitle%3ETest%20tab%3C/title%3E%3C/html%3E]
[task 2021-08-14T21:37:25.064Z] 21:37:25     INFO - GECKO(1539) | [Child 1987: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fb87701f000) [pid = 1987] [serial = 3] [outer = 0] [url = http://example.com/document-builder.sjs?html=%3Chtml%3E%3Ctitle%3ETest%20tab%3C/title%3E%3C/html%3E]
[task 2021-08-14T21:37:25.066Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-14T21:37:25.067Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-14T21:37:25.072Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-14T21:37:25.072Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-14T21:37:25.075Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-14T21:37:25.082Z] 21:37:25     INFO - GECKO(1539) | [Child 1987, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-14T21:37:25.139Z] 21:37:25     INFO - GECKO(1539) | > ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpllde8ow2.mozrunner/runtests_leaks.log
[task 2021-08-14T21:37:25.139Z] 21:37:25     INFO - GECKO(1539) | [2099, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:248
[task 2021-08-14T21:37:25.209Z] 21:37:25     INFO - GECKO(1539) | > [2099, Main Thread] WARNING: !ShouldProcessUpdates(): launching devtools: file /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4161
<...>
[task 2021-08-14T21:38:14.411Z] 21:38:14     INFO - GECKO(1539) | [Child 1651: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f6f9ca1cc00) [pid = 1651] [serial = 5] [outer = 0] [url = about:blank]
[task 2021-08-14T21:43:24.844Z] 21:43:24     INFO - TEST-INFO | started process screentopng
[task 2021-08-14T21:43:25.100Z] 21:43:25     INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-14T21:43:25.100Z] 21:43:25     INFO - Buffered messages logged at 21:37:24
[task 2021-08-14T21:43:25.102Z] 21:43:25     INFO - Entering test bound 
[task 2021-08-14T21:43:25.103Z] 21:43:25     INFO - Buffered messages logged at 21:37:25
[task 2021-08-14T21:43:25.104Z] 21:43:25     INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Browser toolbox started
[task 2021-08-14T21:43:25.106Z] 21:43:25     INFO -  - 
[task 2021-08-14T21:43:25.107Z] 21:43:25     INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Has session state - 
[task 2021-08-14T21:43:25.108Z] 21:43:25     INFO - Buffered messages logged at 21:38:54
[task 2021-08-14T21:43:25.109Z] 21:43:25     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 3
[task 2021-08-14T21:43:25.110Z] 21:43:25     INFO - Buffered messages logged at 21:40:24
[task 2021-08-14T21:43:25.111Z] 21:43:25     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 2
[task 2021-08-14T21:43:25.112Z] 21:43:25     INFO - Buffered messages logged at 21:41:54
[task 2021-08-14T21:43:25.113Z] 21:43:25     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
[task 2021-08-14T21:43:25.114Z] 21:43:25     INFO - Buffered messages finished
[task 2021-08-14T21:43:25.115Z] 21:43:25     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | Test timed out - 
[task 2021-08-14T21:43:25.121Z] 21:43:25     INFO - TEST-PASS | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-08-14T21:43:25.122Z] 21:43:25     INFO - GECKO(1539) | MEMORY STAT | vsize 3370MB | residentFast 630MB | heapAllocated 342MB
[task 2021-08-14T21:43:25.123Z] 21:43:25     INFO - TEST-OK | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_contentframe_inspector.js | took 360152ms
[task 2021-08-14T21:43:25.124Z] 21:43:25     INFO - GECKO(1539) | [Child 1720: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f2ddd67a800 == 1 [pid = 1720] [id = 2]
[task 2021-08-14T21:43:25.125Z] 21:43:25     INFO - GECKO(1539) | [Child 1720: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f2df7d64200) [pid = 1720] [serial = 6] [outer = 0]
[task 2021-08-14T21:43:25.126Z] 21:43:25     INFO - GECKO(1539) | [Child 1720: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f2ddd67bc00) [pid = 1720] [serial = 7] [outer = 7f2df7d64200]
[task 2021-08-14T21:43:25.126Z] 21:43:25     INFO - GECKO(1539) | JavaScript warning: resource://devtools/shared/builtin-modules.js, line 201: debuggee 'resource://devtools/shared/base-loader.js:289' would run
[task 2021-08-14T21:43:25.127Z] 21:43:25     INFO - checking window state
[task 2021-08-14T21:43:25.128Z] 21:43:25     INFO - GECKO(1539) | must wait for focus
[task 2021-08-14T21:43:25.304Z] 21:43:25     INFO - Console message: [JavaScript Warning: "debuggee 'resource://devtools/shared/base-loader.js:289' would run" {file: "resource://devtools/shared/builtin-modules.js" line: 201}]
[task 2021-08-14T21:43:25.310Z] 21:43:25     INFO - TEST-START | devtools/client/framework/browser-toolbox/test/browser_browser_toolbox_fission_inspector.js
Assignee: nobody → abutkovits
Status: NEW → ASSIGNED
Pushed by abutkovits@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/b9d9e5ea2a47 disable browser_browser_toolbox_fission_contentframe_inspector.js on Linux_64_QR_debug for frequent failures. r=intermittent-reviewers,bhearsum DONTBUILD
Status: ASSIGNED → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 93 Branch
Assignee: abutkovits → nobody
Status: RESOLVED → REOPENED
Keywords: leave-open
Resolution: FIXED → ---
Whiteboard: dt-fission-future [stockwell needswork:owner] → dt-fission-future [stockwell needswork:owner][stockwell disabled]
Target Milestone: 93 Branch → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: