1914582 - Intermittent Mn | <random> Actor 'MarionetteCommands' destroyed before query 'MarionetteCommandsParent:executeScript' was resolved

Status: RESOLVED FIXED

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

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

---

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+2]]

This might be a new kind of error based on my changes from bug 1761634. Usually we don't expect the browsing context to get destroyed when no navigation takes place or the tab/window is closed.

[task 2024-08-22T18:14:54.468Z] 18:14:54     INFO -  1724350494468	Marionette	DEBUG	18 <- [1,57,null,["e3d425d5-4df9-40c4-a655-50e4dfeae628","125c24a5-c0b3-4d30-80cb-1a7fd5814db6","a0dfe7cd-837e-42c9-add8-1c0c69cae69c"]]
[task 2024-08-22T18:14:54.469Z] 18:14:54     INFO -  1724350494469	Marionette	DEBUG	18 -> [0,58,"Marionette:SetContext",{"value":"content"}]
[task 2024-08-22T18:14:54.469Z] 18:14:54     INFO -  1724350494469	Marionette	DEBUG	18 <- [1,58,null,{"value":null}]
[task 2024-08-22T18:14:54.470Z] 18:14:54     INFO -  1724350494470	Marionette	DEBUG	18 -> [0,59,"Marionette:SetContext",{"value":"content"}]
[task 2024-08-22T18:14:54.471Z] 18:14:54     INFO -  1724350494470	Marionette	DEBUG	18 <- [1,59,null,{"value":null}]
[task 2024-08-22T18:14:54.471Z] 18:14:54     INFO -  1724350494471	Marionette	DEBUG	18 -> [0,60,"WebDriver:SwitchToWindow",{"handle":"a0dfe7cd-837e-42c9-add8-1c0c69cae69c","focus":true}]
[task 2024-08-22T18:14:54.479Z] 18:14:54     INFO -  1724350494479	Marionette	DEBUG	18 <- [1,60,null,{"value":null}]
[task 2024-08-22T18:14:54.488Z] 18:14:54     INFO -  1724350494488	Marionette	DEBUG	18 -> [0,61,"Marionette:GetContext",{}]
[task 2024-08-22T18:14:54.489Z] 18:14:54     INFO -  1724350494489	Marionette	DEBUG	18 <- [1,61,null,{"value":"content"}]
[task 2024-08-22T18:14:54.490Z] 18:14:54     INFO -  1724350494489	Marionette	DEBUG	18 -> [0,62,"Marionette:SetContext",{"value":"content"}]
[task 2024-08-22T18:14:54.490Z] 18:14:54     INFO -  1724350494490	Marionette	DEBUG	18 <- [1,62,null,{"value":null}]
[task 2024-08-22T18:14:54.492Z] 18:14:54     INFO -  1724350494492	Marionette	DEBUG	18 -> [0,63,"WebDriver:GetWindowHandle",{}]
[task 2024-08-22T18:14:54.493Z] 18:14:54     INFO -  1724350494493	Marionette	DEBUG	18 <- [1,63,null,{"value":"a0dfe7cd-837e-42c9-add8-1c0c69cae69c"}]
[task 2024-08-22T18:14:54.495Z] 18:14:54     INFO -  1724350494494	Marionette	DEBUG	18 -> [0,64,"Marionette:SetContext",{"value":"content"}]
[task 2024-08-22T18:14:54.495Z] 18:14:54     INFO -  1724350494494	Marionette	DEBUG	18 <- [1,64,null,{"value":null}]
[task 2024-08-22T18:14:54.496Z] 18:14:54     INFO -  1724350494496	Marionette	DEBUG	18 -> [0,65,"WebDriver:ExecuteScript",{"script":"return true;","args":[],"newSandbox":true,"sandbox":"default","line":239,"filename":"D:\\task_172434520558680\\build\\tests\\marionette\\tests\\testing\\marionette\\harness\\marionette_harness\\tests\\unit\\test_switch_window_content.py"}]
[task 2024-08-22T18:14:54.499Z] 18:14:54     INFO -  1724350494499	Marionette	TRACE	[50] Browsing context destroyed or inactive
[task 2024-08-22T18:14:54.500Z] 18:14:54     INFO -  1724350494500	Marionette	DEBUG	18 <- [1,65,{"error":"javascript error","message":"Script evaluation aborted: Actor 'MarionetteCommands' destroyed before query 'MarionetteCommandsParent:executeScript' was resolved","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... 63:5\ngetMarionetteCommandsActorProxy/get/<@chrome://remote/content/marionette/actors/MarionetteCommandsParent.sys.mjs:376:23\n"},null]
[task 2024-08-22T18:14:54.501Z] 18:14:54     INFO -  1724350494501	Marionette	TRACE	[50] MarionetteCommands actor created for window id 108
[task 2024-08-22T18:14:54.503Z] 18:14:54     INFO -  1724350494503	Marionette	TRACE	[50] MarionetteCommands actor destroyed for window id 108
[task 2024-08-22T18:14:54.523Z] 18:14:54     INFO -  1724350494523	Marionette	DEBUG	18 -> [0,66,"Marionette:GetContext",{}]
[task 2024-08-22T18:14:54.524Z] 18:14:54     INFO -  1724350494523	Marionette	DEBUG	18 <- [1,66,null,{"value":"content"}]

Interesting is that the browsing context id for the destroyed actor is actually equal to the one newly created. Maybe we see just a replacement without a process change?

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1914582&startday=2024-08-19&endday=2024-08-25&tree=all

Comment 6

[Intermittent Failures Robot]

13 failures in 3879 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 8
• try: 1

Platform and build breakdown:

• linux1804-64-clang-trunk-qr: 1
  • opt: 1
• macosx1015-64-shippable-qr: 2
  • opt: 2
• linux1804-64-shippable-qr: 2
  • opt: 2
• windows11-64-shippable-qr: 7
  • opt: 7
• windows11-64-2009-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1914582&startday=2024-08-26&endday=2024-09-01&tree=all

Comment 7

[Henrik Skupin [:whimboo][⌚️UTC+2]]

Backout happened on bug 1761634 so this failure should now disappear.