Closed Bug 1851330 Opened 2 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/perform_actions/pointer_contextmenu.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1791951

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2023-09-02T18:55:24.217Z] 18:55:24     INFO - TEST-START | /webdriver/tests/classic/perform_actions/pointer_contextmenu.py
[task 2023-09-02T18:55:24.262Z] 18:55:24     INFO - STDOUT: ============================= test session starts ==============================
[task 2023-09-02T18:55:24.263Z] 18:55:24     INFO - STDOUT: platform linux -- Python 3.7.5, pytest-7.0.1, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2023-09-02T18:55:24.264Z] 18:55:24     INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2023-09-02T18:55:24.264Z] 18:55:24     INFO - STDOUT: plugins: asyncio-0.19.0
[task 2023-09-02T18:55:24.265Z] 18:55:24     INFO - STDOUT: asyncio: mode=strict
[task 2023-09-02T18:55:24.265Z] 18:55:24     INFO - STDOUT: collecting ... 
[task 2023-09-02T18:55:24.272Z] 18:55:24     INFO - STDOUT: collected 3 items
[task 2023-09-02T18:55:24.272Z] 18:55:24     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/perform_actions/pointer_contextmenu.py::test_control_click[\ue009-ctrlKey] 
[task 2023-09-02T18:55:24.273Z] 18:55:24     INFO - PID 16379 | 1693680924271	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmphoz8p464.mozrunner"]}}}}
[task 2023-09-02T18:55:24.273Z] 18:55:24     INFO - PID 16379 | 1693680924271	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2023-09-02T18:55:24.274Z] 18:55:24     INFO - PID 16379 | 1693680924271	geckodriver::capabilities	DEBUG	Found version 118.0
[task 2023-09-02T18:55:24.274Z] 18:55:24     INFO - PID 16379 | 1693680924272	geckodriver::browser	DEBUG	Backing up prefs to "/tmp/tmphoz8p464.mozrunner/user.geckodriver_backup"
[task 2023-09-02T18:55:24.278Z] 18:55:24     INFO - PID 16379 | 1693680924276	mozrunner::runner	INFO	Running command: MOZ_CRASHREPORTER="1" MOZ_CRASHREPORTER_NO_REPORT="1" MOZ_CRASHREPORTER_SHUTDOWN="1" MOZ_NO_REMOTE="1" "/builds/worker/workspace/build/application/firefox/firefox" "--marionette" "--profile" "/tmp/tmphoz8p464.mozrunner" "-no-remote"
[task 2023-09-02T18:55:24.278Z] 18:55:24     INFO - PID 16379 | 1693680924277	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2023-09-02T18:55:24.278Z] 18:55:24     INFO - PID 16379 | 1693680924277	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.279Z] 18:55:24     INFO - PID 16379 | 1693680924277	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:24.378Z] 18:55:24     INFO - PID 16379 | 1693680924377	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.379Z] 18:55:24     INFO - PID 16379 | 1693680924377	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:24.478Z] 18:55:24     INFO - PID 16379 | 1693680924477	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.479Z] 18:55:24     INFO - PID 16379 | 1693680924477	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:24.578Z] 18:55:24     INFO - PID 16379 | 1693680924577	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.579Z] 18:55:24     INFO - PID 16379 | 1693680924577	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:24.647Z] 18:55:24     INFO - PID 16379 | console.warn: services.settings: Ignoring preference override of remote settings server
[task 2023-09-02T18:55:24.648Z] 18:55:24     INFO - PID 16379 | console.warn: services.settings: Allow by setting MOZ_REMOTE_SETTINGS_DEVTOOLS=1 in the environment
[task 2023-09-02T18:55:24.678Z] 18:55:24     INFO - PID 16379 | 1693680924677	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.679Z] 18:55:24     INFO - PID 16379 | 1693680924677	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:24.778Z] 18:55:24     INFO - PID 16379 | 1693680924777	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.779Z] 18:55:24     INFO - PID 16379 | 1693680924777	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:24.878Z] 18:55:24     INFO - PID 16379 | 1693680924878	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.879Z] 18:55:24     INFO - PID 16379 | 1693680924878	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:24.979Z] 18:55:24     INFO - PID 16379 | 1693680924978	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:24.979Z] 18:55:24     INFO - PID 16379 | 1693680924978	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.079Z] 18:55:25     INFO - PID 16379 | 1693680925078	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.079Z] 18:55:25     INFO - PID 16379 | 1693680925078	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.179Z] 18:55:25     INFO - PID 16379 | 1693680925178	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.180Z] 18:55:25     INFO - PID 16379 | 1693680925178	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.279Z] 18:55:25     INFO - PID 16379 | 1693680925278	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.280Z] 18:55:25     INFO - PID 16379 | 1693680925278	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.379Z] 18:55:25     INFO - PID 16379 | 1693680925378	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.380Z] 18:55:25     INFO - PID 16379 | 1693680925378	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.480Z] 18:55:25     INFO - PID 16379 | 1693680925478	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.480Z] 18:55:25     INFO - PID 16379 | 1693680925479	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.580Z] 18:55:25     INFO - PID 16379 | 1693680925579	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.580Z] 18:55:25     INFO - PID 16379 | 1693680925579	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.680Z] 18:55:25     INFO - PID 16379 | 1693680925679	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.680Z] 18:55:25     INFO - PID 16379 | 1693680925679	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.780Z] 18:55:25     INFO - PID 16379 | 1693680925779	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.781Z] 18:55:25     INFO - PID 16379 | 1693680925779	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.810Z] 18:55:25     INFO - PID 16379 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-09-02T18:55:25.849Z] 18:55:25     INFO - PID 16379 | 1693680925849	Marionette	INFO	Marionette enabled
[task 2023-09-02T18:55:25.855Z] 18:55:25     INFO - PID 16379 | 1693680925854	Marionette	TRACE	Received observer notification final-ui-startup
[task 2023-09-02T18:55:25.880Z] 18:55:25     INFO - PID 16379 | 1693680925879	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.880Z] 18:55:25     INFO - PID 16379 | 1693680925879	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:25.980Z] 18:55:25     INFO - PID 16379 | 1693680925979	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:25.981Z] 18:55:25     INFO - PID 16379 | 1693680925979	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:26.035Z] 18:55:26     INFO - PID 16379 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-09-02T18:55:26.080Z] 18:55:26     INFO - PID 16379 | 1693680926079	geckodriver::browser	TRACE	Failed to open /tmp/tmphoz8p464.mozrunner/MarionetteActivePort
[task 2023-09-02T18:55:26.081Z] 18:55:26     INFO - PID 16379 | 1693680926079	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-02T18:55:26.135Z] 18:55:26     INFO - PID 16379 | 1693680926134	Marionette	INFO	Listening on port 37863
[task 2023-09-02T18:55:26.136Z] 18:55:26     INFO - PID 16379 | 1693680926135	Marionette	DEBUG	Marionette is listening
[task 2023-09-02T18:55:26.180Z] 18:55:26     INFO - PID 16379 | Read port: 37863
[task 2023-09-02T18:55:26.269Z] 18:55:26     INFO - PID 16379 | 1693680926268	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51154
[task 2023-09-02T18:55:26.722Z] 18:55:26     INFO - PID 16379 | 1693680926721	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:37863.
[task 2023-09-02T18:55:26.775Z] 18:55:26     INFO - PID 16379 | 1693680926774	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{}]
[task 2023-09-02T18:55:26.785Z] 18:55:26     INFO - PID 16379 | 1693680926784	Marionette	DEBUG	Waiting for initial application window
[task 2023-09-02T18:55:32.214Z] 18:55:32     INFO - PID 16379 | console.error: ({})
[task 2023-09-02T18:55:35.906Z] 18:55:35     INFO - PID 16379 | 1693680935905	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2023-09-02T18:55:35.928Z] 18:55:35     INFO - PID 16379 | 1693680935927	RemoteAgent	TRACE	[3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2023-09-02T18:55:35.928Z] 18:55:35     INFO - PID 16379 | 1693680935928	RemoteAgent	TRACE	[3] ProgressListener Document already loading about:blank
[task 2023-09-02T18:55:35.930Z] 18:55:35     INFO - PID 16379 | 1693680935929	RemoteAgent	TRACE	[3] ProgressListener Check loading state: isStart=true isStop=false
[task 2023-09-02T18:55:35.931Z] 18:55:35     INFO - PID 16379 | 1693680935929	RemoteAgent	TRACE	[3] ProgressListener state=start: about:blank
[task 2023-09-02T18:58:49.217Z] 18:58:49     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/perform_actions/pointer_contextmenu.py | expected OK
[task 2023-09-02T18:58:49.218Z] 18:58:49     INFO - TEST-INFO took 205002ms
[task 2023-09-02T18:58:55.256Z] 18:58:55     INFO - Closing logging queue
[task 2023-09-02T18:58:55.257Z] 18:58:55     INFO - queue closed
[task 2023-09-02T18:58:55.267Z] 18:58:55     INFO - PID 19403 | 1693681135266	geckodriver	INFO	Listening on 127.0.0.1:43358
[task 2023-09-02T18:58:55.267Z] 18:58:55     INFO - Starting runner
[task 2023-09-02T18:58:55.475Z] 18:58:55     INFO - TEST-START | /webdriver/tests/classic/perform_actions/pointer_dblclick.py
Status: NEW → RESOLVED
Closed: 2 years ago
Duplicate of bug: 1791951
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.