Closed Bug 1433621 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-ERROR | /fetch/security/redirect-to-url-with-credentials.https.html |

Categories

(Core :: DOM: Core & HTML, defect, P3)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

This bug has failed 31 times in the last 7 days on Linux, Windows and OX.10 affecting debug and opt build types.
Failing tests: debug-web-platform-tests-e10, opt-web-platform-tests-e10.

Link to a recent log:https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=160799180&lineNumber=13785

Part of that log: [task 2018-02-07T11:18:01.009Z] 11:18:01     INFO - PID 4915 | [Parent 4915, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98
[task 2018-02-07T11:18:01.095Z] 11:18:01     INFO - TEST-UNEXPECTED-ERROR | /fetch/security/redirect-to-url-with-credentials.https.html | 
[task 2018-02-07T11:18:01.096Z] 11:18:01     INFO - stacktrace:
[task 2018-02-07T11:18:01.096Z] 11:18:01     INFO - 	WebDriverError@chrome://marionette/content/error.js:170:5
[task 2018-02-07T11:18:01.097Z] 11:18:01     INFO - 	UnexpectedAlertOpenError@chrome://marionette/content/error.js:454:5
[task 2018-02-07T11:18:01.099Z] 11:18:01     INFO - 	assert.that/<@chrome://marionette/content/assert.js:405:13
[task 2018-02-07T11:18:01.099Z] 11:18:01     INFO - 	assert.noUserPrompt@chrome://marionette/content/assert.js:178:3
[task 2018-02-07T11:18:01.099Z] 11:18:01     INFO - 	GeckoDriver.prototype._assertAndDismissModal@chrome://marionette/content/driver.js:3167:5
[task 2018-02-07T11:18:01.100Z] 11:18:01     INFO - 	GeckoDriver.prototype.close@chrome://marionette/content/driver.js:2700:3
[task 2018-02-07T11:18:01.100Z] 11:18:01     INFO - 	Async*despatch@chrome://marionette/content/server.js:557:20
[task 2018-02-07T11:18:01.100Z] 11:18:01     INFO - 	async*execute@chrome://marionette/content/server.js:531:11
[task 2018-02-07T11:18:01.101Z] 11:18:01     INFO - 	async*onPacket/<@chrome://marionette/content/server.js:506:15
[task 2018-02-07T11:18:01.101Z] 11:18:01     INFO - 	async*onPacket@chrome://marionette/content/server.js:505:8 

:overholt could you please take a look?
Flags: needinfo?(overholt)
Whiteboard: [stockwell needswork]
302 mdaly :)
Flags: needinfo?(overholt) → needinfo?(mdaly)
Is this related to your current bugs?
Flags: needinfo?(mdaly) → needinfo?(amarchesini)
Not really. It seems related to a marionette error. :ato, do you have any idea why this happens? Can we have a better error message?
Flags: needinfo?(amarchesini) → needinfo?(ato)
This isn’t a Marionette error as such, but the stacktrace can
give can impression.  This is telling us that a modal dialogue
(such as a window.alert user prompt or a global dialogue) prevented
the harness from closing the window.

The above paste doesn’t include the full stacktrace:

> stacktrace:
> 	WebDriverError@chrome://marionette/content/error.js:170:5
> 	UnexpectedAlertOpenError@chrome://marionette/content/error.js:454:5
> 	assert.that/<@chrome://marionette/content/assert.js:405:13
> 	assert.noUserPrompt@chrome://marionette/content/assert.js:178:3
> 	GeckoDriver.prototype._assertAndDismissModal@chrome://marionette/content/driver.js:3167:5
> 	GeckoDriver.prototype.close@chrome://marionette/content/driver.js:2700:3
> 	Async*despatch@chrome://marionette/content/server.js:557:20
> 	async*execute@chrome://marionette/content/server.js:531:11
> 	async*onPacket/<@chrome://marionette/content/server.js:506:15
> 	async*onPacket@chrome://marionette/content/server.js:505:8
> 	_onJSONObjectReady/<@chrome://marionette/content/transport.js:500:9
> 	openModalWindow@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:353:5
> 	openPrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:563:9
> 	confirmEx@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:704:9
> 	openModalWindow@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:353:5
> 	openPrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:563:9
> 	confirmEx@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:704:9

An educated guess would be that the test is causing a browser with
multiple tabs open to shut down (confirmEx), which causes the
“Are you really sure you want to exit?” dialogue to appear.
The WPT harness then tries to clean up the open windows before the
next test runs, and fails doing that.  Can we confirm somehow that
this is the dialogue that is being opened?  (I haven’t looked at
the actual test yet.)
Flags: needinfo?(ato)
Hold on, the GeckoDriver#_assertAndDismissModal is supposed to
close any such dialogue before the window/tab is closed.  The call
to assert.noUserPrompt is wrapped in a try…catch, which means
there is something wrong with the error that is being passed to
UnexpectedAlertOpenError.  It inherits from WebDriverError, and
that passes the first error argument to super [1].

  [1] https://searchfox.org/mozilla-central/rev/a539f8f7fe288d0b4d8d6a32510be20f52bb7a0f/testing/marionette/error.js#170
I’m running a try run with some more debug output to tell what’s
wrong with super(x):

  https://treeherder.mozilla.org/#/jobs?repo=try&revision=3f0c0cb422286f2b5dad93d7a89bec6c055a8ca0
Over the last 7 days there are 31 failures present on this bug. These happen on Linux, Linux x64, linux32-stylo-disabled, linux64-ccov, windows10-64-ccov

Here is the most recent log example: https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=162879348&lineNumber=11694

Here is a relevant part of that log: 

[task 2018-02-18T11:36:55.210Z] 11:36:55     INFO - TEST-UNEXPECTED-ERROR | /fetch/security/redirect-to-url-with-credentials.https.html | 
[task 2018-02-18T11:36:55.211Z] 11:36:55     INFO - stacktrace:
[task 2018-02-18T11:36:55.211Z] 11:36:55     INFO - 	WebDriverError@chrome://marionette/content/error.js:170:5
[task 2018-02-18T11:36:55.212Z] 11:36:55     INFO - 	UnexpectedAlertOpenError@chrome://marionette/content/error.js:454:5
[task 2018-02-18T11:36:55.212Z] 11:36:55     INFO - 	assert.that/<@chrome://marionette/content/assert.js:405:13
[task 2018-02-18T11:36:55.213Z] 11:36:55     INFO - 	assert.noUserPrompt@chrome://marionette/content/assert.js:178:3
[task 2018-02-18T11:36:55.213Z] 11:36:55     INFO - 	GeckoDriver.prototype._assertAndDismissModal@chrome://marionette/content/driver.js:3171:5
[task 2018-02-18T11:36:55.214Z] 11:36:55     INFO - 	GeckoDriver.prototype.close@chrome://marionette/content/driver.js:2704:3
[task 2018-02-18T11:36:55.215Z] 11:36:55     INFO - 	Async*despatch@chrome://marionette/content/server.js:551:20
[task 2018-02-18T11:36:55.215Z] 11:36:55     INFO - 	async*execute@chrome://marionette/content/server.js:525:11
[task 2018-02-18T11:36:55.216Z] 11:36:55     INFO - 	async*onPacket/<@chrome://marionette/content/server.js:500:15
[task 2018-02-18T11:36:55.217Z] 11:36:55     INFO - 	async*onPacket@chrome://marionette/content/server.js:499:8
[task 2018-02-18T11:36:55.218Z] 11:36:55     INFO - 	_onJSONObjectReady/<@chrome://marionette/content/transport.js:500:9
[task 2018-02-18T11:36:55.218Z] 11:36:55     INFO - 	openModalWindow@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:353:5
[task 2018-02-18T11:36:55.219Z] 11:36:55     INFO - 	openPrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:563:9
[task 2018-02-18T11:36:55.219Z] 11:36:55     INFO - 	confirmEx@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:704:9
[task 2018-02-18T11:36:55.220Z] 11:36:55     INFO - 	openModalWindow@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:353:5
[task 2018-02-18T11:36:55.220Z] 11:36:55     INFO - 	openPrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:563:9
[task 2018-02-18T11:36:55.221Z] 11:36:55     INFO - 	confirmEx@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:704:9
[task 2018-02-18T11:36:55.221Z] 11:36:55     INFO - TEST-INFO took 781ms
Flags: needinfo?(overholt)
I think Andreas was working on this so I'll redirect to him.
Flags: needinfo?(overholt) → needinfo?(ato)
Just acknowledging I’ve seen this.
I’m going to look at this after I finish
https://bugzilla.mozilla.org/show_bug.cgi?id=marionette-window-tracking.
Since 11th April there already are 36 failures.
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=174258873&lineNumber=3554
:ato do you have any updates here?
:overholt could you please take a look?
Flags: needinfo?(overholt)
I believe Andreas is still investigating (at least it still looks like a Marionette issue).
Flags: needinfo?(overholt)
There are 39 failures in the past week.
Platforms: Linux opt, Linux x64 opt/pgo and debug, linux32-nightly opt, linux32-nightly opt, linux64-qr opt, windows10-64 pgo, windows10-64-ccov debug.

Recent log failure: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=175320371&lineNumber=3543

Relevant part of the log:
[task 2018-04-24T11:56:43.963Z] 11:56:43     INFO - TEST-UNEXPECTED-ERROR | /fetch/security/redirect-to-url-with-credentials.https.html | 
[task 2018-04-24T11:56:43.964Z] 11:56:43     INFO - stacktrace:
[task 2018-04-24T11:56:43.964Z] 11:56:43     INFO - 	WebDriverError@chrome://marionette/content/error.js:178:5
[task 2018-04-24T11:56:43.965Z] 11:56:43     INFO - 	UnexpectedAlertOpenError@chrome://marionette/content/error.js:461:5
[task 2018-04-24T11:56:43.965Z] 11:56:43     INFO - 	assert.that/<@chrome://marionette/content/assert.js:405:13
[task 2018-04-24T11:56:43.965Z] 11:56:43     INFO - 	assert.noUserPrompt@chrome://marionette/content/assert.js:178:3
[task 2018-04-24T11:56:43.966Z] 11:56:43     INFO - 	GeckoDriver.prototype._assertAndDismissModal@chrome://marionette/content/driver.js:3189:5
[task 2018-04-24T11:56:43.966Z] 11:56:43     INFO - 	GeckoDriver.prototype.close@chrome://marionette/content/driver.js:2722:3
[task 2018-04-24T11:56:43.966Z] 11:56:43     INFO - 	Async*despatch@chrome://marionette/content/server.js:297:20
[task 2018-04-24T11:56:43.966Z] 11:56:43     INFO - 	async*execute@chrome://marionette/content/server.js:271:11
[task 2018-04-24T11:56:43.967Z] 11:56:43     INFO - 	async*onPacket/<@chrome://marionette/content/server.js:246:15
[task 2018-04-24T11:56:43.967Z] 11:56:43     INFO - 	async*onPacket@chrome://marionette/content/server.js:245:8
[task 2018-04-24T11:56:43.967Z] 11:56:43     INFO - 	_onJSONObjectReady/<@chrome://marionette/content/transport.js:500:9
[task 2018-04-24T11:56:43.968Z] 11:56:43     INFO - 	openModalWindow@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:353:5
[task 2018-04-24T11:56:43.968Z] 11:56:43     INFO - 	openPrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:561:9
[task 2018-04-24T11:56:43.968Z] 11:56:43     INFO - 	confirmEx@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:702:9
[task 2018-04-24T11:56:43.969Z] 11:56:43     INFO - 	openModalWindow@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:353:5
[task 2018-04-24T11:56:43.969Z] 11:56:43     INFO - 	openPrompt@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:561:9
[task 2018-04-24T11:56:43.970Z] 11:56:43     INFO - 	confirmEx@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsPrompter.js:702:9

:ato any updates here?
Priority: -- → P3
It is sadly unlikely at this point that I will get around to this
in reasonable time.
Flags: needinfo?(ato)
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Component: DOM → DOM: Core & HTML
You need to log in before you can comment on or make changes to this bug.