1673327 - (actors) Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_reftest.py TestReftest.test_cache_multiple_sizes | AssertionError: u'PASS' != u'ERROR'

Status: RESOLVED FIXED

(Testing :: Marionette Client and Harness, defect, P5)

Description

[Treeherder Bug Filer]

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

---

Comment 1

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

Here the failing lines:

[task 2020-10-26T10:30:02.014Z] 10:30:02     INFO -  1603708202003	Marionette	DEBUG	8 -> [0,10,"reftest:setup",{"screenshot":"unexpected"}]
[task 2020-10-26T10:30:02.014Z] 10:30:02     INFO -  1603708202008	Marionette	DEBUG	8 <- [1,10,null,{"value":null}]
[task 2020-10-26T10:30:02.014Z] 10:30:02     INFO -  1603708202009	Marionette	DEBUG	8 -> [0,11,"reftest:run",{"width":600,"height":600,"expected":"PASS","references":[["http://127.0.0.1:49911/reftest/mostly-teal-700x700.html",[],"=="]],"timeout":10000,"test":"http://127.0.0.1:49911/reftest/teal-700x700.html"}]
[task 2020-10-26T10:30:02.014Z] 10:30:02     INFO -  1603708202010	Marionette	DEBUG	ensuring we have a window 600x600
[task 2020-10-26T10:30:02.014Z] 10:30:02     INFO -  1603708202010	Marionette	DEBUG	Using separate window
[task 2020-10-26T10:30:02.019Z] 10:30:02     INFO -  1603708202017	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2020-10-26T10:30:02.019Z] 10:30:02     INFO -  1603708202017	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2020-10-26T10:30:02.019Z] 10:30:02     INFO -  1603708202017	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2020-10-26T10:30:02.019Z] 10:30:02     INFO -  1603708202017	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2020-10-26T10:30:02.019Z] 10:30:02     INFO -  1603708202017	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2020-10-26T10:30:02.019Z] 10:30:02     INFO -  1603708202017	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2020-10-26T10:30:02.019Z] 10:30:02     INFO -  1603708202017	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2020-10-26T10:30:02.024Z] 10:30:02     INFO -  [Parent 8884, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4282
[task 2020-10-26T10:30:02.083Z] 10:30:02     INFO -  DEBUG: Adding blocker ContentParent: id=1e2084d4000 for phase xpcom-will-shutdown
[task 2020-10-26T10:30:02.083Z] 10:30:02     INFO -  DEBUG: Adding blocker ContentParent: id=1e2084d4000 for phase profile-before-change
[task 2020-10-26T10:30:02.102Z] 10:30:02     INFO -  DEBUG: Adding blocker ContentParent: id=1e201fc8000 for phase xpcom-will-shutdown
[task 2020-10-26T10:30:02.104Z] 10:30:02     INFO -  DEBUG: Adding blocker ContentParent: id=1e201fc8000 for phase profile-before-change
[task 2020-10-26T10:30:02.244Z] 10:30:02     INFO -  [Child 6116, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2020-10-26T10:30:02.254Z] 10:30:02     INFO -  [Child 6116, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-26T10:30:02.264Z] 10:30:02     INFO -  1603708202259	Marionette	TRACE	[37] Child actor created for window id 28
[task 2020-10-26T10:30:02.269Z] 10:30:02     INFO -  1603708202265	Marionette	TRACE	[37] Parent actor created
[task 2020-10-26T10:30:02.279Z] 10:30:02     INFO -  1603708202276	Marionette	TRACE	[37] Frame script loaded
[task 2020-10-26T10:30:02.298Z] 10:30:02     INFO -  1603708202295	Marionette	DEBUG	8 <- [1,11,null,{"value":{"status":"ERROR","message":"AbortError: Actor 'MarionetteFrame' destroyed before query 'MarionetteFrameParent:getCurrentUrl' was resolved","stack":"","extra":{}}}]

Navigation code in Marionette has to check for the current URL before navigating, and that doesn't handle a possible discarding of the actor. Julian is working on that via bug 1671347, and that should fix it.

Comment 2

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

Indeed, it's fixed by the patch on bug 1671347 now.

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64: 1

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

Comment 4

[BMO Automation]

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.