Closed Bug 1768967 Opened 2 years ago Closed 2 years ago

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

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1630162

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


task 2022-05-11T22:31:53.715Z] 22:31:53     INFO - TEST-START | /webdriver/tests/element_click/stale.py
[task 2022-05-11T22:31:53.767Z] 22:31:53     INFO - STDOUT: ============================= test session starts ==============================
[task 2022-05-11T22:31:53.768Z] 22:31:53     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-05-11T22:31:53.769Z] 22:31:53     INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2022-05-11T22:31:53.770Z] 22:31:53     INFO - STDOUT: plugins: asyncio-0.14.0
[task 2022-05-11T22:31:53.771Z] 22:31:53     INFO - STDOUT: collecting ... 
[task 2022-05-11T22:31:53.774Z] 22:31:53     INFO - STDOUT: collected 1 item
[task 2022-05-11T22:31:53.777Z] 22:31:53     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/element_click/stale.py::test_is_stale 
[task 2022-05-11T22:31:53.777Z] 22:31:53     INFO - PID 4865 | 1652308313775	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmp2429nwi6.mozrunner"], "binary": "/builds/worker/workspace/build/application/firefox/firefox"}}}}
[task 2022-05-11T22:31:53.777Z] 22:31:53     INFO - PID 4865 | 1652308313775	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2022-05-11T22:31:53.778Z] 22:31:53     INFO - PID 4865 | 1652308313776	geckodriver::capabilities	DEBUG	Found version 102.0a1
[task 2022-05-11T22:31:53.778Z] 22:31:53     INFO - PID 4865 | 1652308313776	geckodriver::browser	DEBUG	Backing up prefs to "/tmp/tmp2429nwi6.mozrunner/user.geckodriver_backup"
[task 2022-05-11T22:31:53.782Z] 22:31:53     INFO - PID 4865 | 1652308313782	mozrunner::runner	INFO	Running command: "/builds/worker/workspace/build/application/firefox/firefox" "--marionette" "--profile" "/tmp/tmp2429nwi6.mozrunner" "-no-remote"
[task 2022-05-11T22:31:53.784Z] 22:31:53     INFO - PID 4865 | 1652308313783	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2022-05-11T22:31:53.784Z] 22:31:53     INFO - PID 4865 | 1652308313783	geckodriver::browser	TRACE	Failed to open /tmp/tmp2429nwi6.mozrunner/MarionetteActivePort
[task 2022-05-11T22:31:53.785Z] 22:31:53     INFO - PID 4865 | 1652308313783	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2022-05-11T22:31:53.803Z] 22:31:53     INFO - PID 4865 | *** You are running in headless mode.
[task 2022-05-11T22:31:53.885Z] 22:31:53     INFO - PID 4865 | 1652308313883	geckodriver::browser	TRACE	Failed to open /tmp/tmp2429nwi6.mozrunner/MarionetteActivePort
[task 2022-05-11T22:31:53.886Z] 22:31:53     INFO - PID 4865 | 1652308313883	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2022-05-11T22:31:53.903Z] 22:31:53     INFO - PID 4865 | 1652308313902	Marionette	INFO	Marionette enabled
[task 2022-05-11T22:31:53.904Z] 22:31:53     INFO - PID 4865 | 1652308313903	Marionette	TRACE	Received observer notification final-ui-startup
[task 2022-05-11T22:31:53.905Z] 22:31:53     INFO - PID 4865 | 1652308313903	Marionette	INFO	Listening on port 37215
[task 2022-05-11T22:31:53.906Z] 22:31:53     INFO - PID 4865 | 1652308313903	Marionette	DEBUG	Marionette is listening
[task 2022-05-11T22:31:53.984Z] 22:31:53     INFO - PID 4865 | Read port: 37215
<...>
[task 2022-05-11T22:31:54.652Z] 22:31:54     INFO - PID 4865 | 1652308314651	Marionette	INFO	Stopped listening on port 37215
[task 2022-05-11T22:31:54.678Z] 22:31:54     INFO - PID 4865 | 1652308314677	Marionette	TRACE	Received observer notification quit-application
[task 2022-05-11T22:31:54.681Z] 22:31:54     INFO - PID 4865 | 1652308314680	Marionette	TRACE	Received observer notification quit-application
[task 2022-05-11T22:31:54.682Z] 22:31:54     INFO - PID 4865 | 1652308314680	Marionette	DEBUG	Marionette stopped listening
[task 2022-05-11T22:31:54.683Z] 22:31:54     INFO - PID 4865 | 1652308314681	Marionette	DEBUG	0 <- [1,22,null,{"cause":"shutdown","forced":false}]
[task 2022-05-11T22:31:54.693Z] 22:31:54     INFO - PID 4865 | 1652308314692	webdriver::server	DEBUG	Teardown session
[task 2022-05-11T22:31:54.695Z] 22:31:54     INFO - PID 4865 | 1652308314693	Marionette	DEBUG	Closed connection 0
[task 2022-05-11T22:32:23.716Z] 22:32:23     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_click/stale.py | expected OK
[task 2022-05-11T22:32:23.717Z] 22:32:23     INFO - TEST-INFO took 30002ms
[task 2022-05-11T22:32:23.816Z] 22:32:23     INFO - Closing logging queue
[task 2022-05-11T22:32:23.816Z] 22:32:23     INFO - queue closed
[task 2022-05-11T22:32:23.824Z] 22:32:23     INFO - PID 7481 | 1652308343822	geckodriver	INFO	Listening on 127.0.0.1:59428
[task 2022-05-11T22:32:23.824Z] 22:32:23     INFO - Starting runner
[task 2022-05-11T22:32:24.096Z] 22:32:24     INFO - TEST-START | /webdriver/tests/element_click/user_prompts.py

Firefox hung during shutdown.

Status: NEW → RESOLVED
Closed: 2 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.