Closed Bug 1751384 Opened 4 years ago Closed 4 years ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_click/user_prompts.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1676582

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2022-01-21T10:41:58.685Z] 10:41:58     INFO - TEST-START | /webdriver/tests/element_click/user_prompts.py
[task 2022-01-21T10:41:58.737Z] 10:41:58     INFO - STDOUT: ============================= test session starts ==============================
[task 2022-01-21T10:41:58.738Z] 10:41:58     INFO - STDOUT: platform linux -- Python 3.6.9, pytest-6.1.1, py-1.5.2, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2022-01-21T10:41:58.742Z] 10:41:58     INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2022-01-21T10:41:58.742Z] 10:41:58     INFO - STDOUT: plugins: asyncio-0.14.0
[task 2022-01-21T10:41:58.743Z] 10:41:58     INFO - STDOUT: collecting ... 
[task 2022-01-21T10:41:58.752Z] 10:41:58     INFO - STDOUT: collected 18 items
[task 2022-01-21T10:41:58.753Z] 10:41:58     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/element_click/user_prompts.py::test_accept[capabilities0-alert-None] 
<...>
[task 2022-01-21T10:42:15.227Z] 10:42:15     INFO - PID 5331 | 1642761735224	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:15.281Z] 10:42:15     INFO - PID 5331 | [CodeCoverage] Setting handlers for process 6762.
[task 2022-01-21T10:42:15.282Z] 10:42:15     INFO - PID 5331 | *** You are running in headless mode.
[task 2022-01-21T10:42:15.326Z] 10:42:15     INFO - PID 5331 | 1642761735325	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:15.427Z] 10:42:15     INFO - PID 5331 | 1642761735425	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:15.505Z] 10:42:15     INFO - PID 5331 | 1642761735503	Marionette	INFO	Marionette enabled
[task 2022-01-21T10:42:15.527Z] 10:42:15     INFO - PID 5331 | 1642761735525	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:15.627Z] 10:42:15     INFO - PID 5331 | 1642761735625	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:15.727Z] 10:42:15     INFO - PID 5331 | 1642761735725	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:15.827Z] 10:42:15     INFO - PID 5331 | 1642761735826	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:15.927Z] 10:42:15     INFO - PID 5331 | 1642761735926	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.008Z] 10:42:16     INFO - PID 5331 | [GFX1-]: RenderCompositorSWGL failed mapping default framebuffer, no dt
[task 2022-01-21T10:42:16.028Z] 10:42:16     INFO - PID 5331 | 1642761736026	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.128Z] 10:42:16     INFO - PID 5331 | 1642761736126	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.228Z] 10:42:16     INFO - PID 5331 | 1642761736226	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.328Z] 10:42:16     INFO - PID 5331 | 1642761736327	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.429Z] 10:42:16     INFO - PID 5331 | 1642761736427	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.487Z] 10:42:16     INFO - PID 5331 | [CodeCoverage] Setting handlers for process 6824.
[task 2022-01-21T10:42:16.528Z] 10:42:16     INFO - PID 5331 | 1642761736527	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.629Z] 10:42:16     INFO - PID 5331 | 1642761736627	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.729Z] 10:42:16     INFO - PID 5331 | 1642761736727	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.805Z] 10:42:16     INFO - PID 5331 | 1642761736802	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2022-01-21T10:42:16.805Z] 10:42:16     INFO - PID 5331 | 1642761736802	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2022-01-21T10:42:16.829Z] 10:42:16     INFO - PID 5331 | 1642761736827	geckodriver::marionette	TRACE	Connection refused (os error 111). Retrying in 100ms
[task 2022-01-21T10:42:16.836Z] 10:42:16     INFO - PID 5331 | 1642761736834	Marionette	TRACE	All scripts recorded.
[task 2022-01-21T10:42:16.838Z] 10:42:16     INFO - PID 5331 | 1642761736836	Marionette	INFO	Listening on port 2828
[task 2022-01-21T10:42:16.840Z] 10:42:16     INFO - PID 5331 | 1642761736836	Marionette	DEBUG	Marionette is listening
[task 2022-01-21T10:42:16.878Z] 10:42:16     INFO - PID 5331 | [CodeCoverage] Setting handlers for process 6837.
[task 2022-01-21T10:42:16.938Z] 10:42:16     INFO - PID 5331 | 1642761736937	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:37994
[task 2022-01-21T10:42:16.946Z] 10:42:16     INFO - PID 5331 | 1642761736944	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:2828.
[task 2022-01-21T10:42:16.957Z] 10:42:16     INFO - PID 5331 | 1642761736955	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{"unhandledPromptBehavior":"dismiss"}]
[task 2022-01-21T10:48:03.683Z] 10:48:03     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_click/user_prompts.py | expected OK
[task 2022-01-21T10:48:03.685Z] 10:48:03     INFO - TEST-INFO took 365001ms
[task 2022-01-21T10:48:03.685Z] 10:48:03     INFO - Restarting browser for new test group
[task 2022-01-21T10:48:03.848Z] 10:48:03     INFO - Closing logging queue
[task 2022-01-21T10:48:03.849Z] 10:48:03     INFO - queue closed
[task 2022-01-21T10:48:03.851Z] 10:48:03     INFO - Starting runner
[task 2022-01-21T10:48:04.281Z] 10:48:04     INFO - PID 6923 | 1642762084278	geckodriver	INFO	Listening on 127.0.0.1:46121
[task 2022-01-21T10:48:04.282Z] 10:48:04     INFO - WebDriver HTTP server listening at http://127.0.0.1:46121/
[task 2022-01-21T10:48:04.282Z] 10:48:04     INFO - TEST-START | /webdriver/tests/find_element/find.py

It's hanging in WebDriver:NewSession because until now no top-level window has been opened since the start of Firefox:

[task 2022-01-21T10:42:16.957Z] 10:42:16     INFO - PID 5331 | 1642761736955	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{"unhandledPromptBehavior":"dismiss"}]
[task 2022-01-21T10:48:03.683Z] 10:48:03     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_click/user_prompts.py | expected OK

This looks like a broken build.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.