Closed Bug 1908141 Opened 8 months ago Closed 7 months ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/element_click/bubbling.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

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=466538907&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/K5FzqiTLTmqwXHlJR8oz-g/runs/0/artifacts/public/logs/live_backing.log

[task 2024-07-16T13:09:12.510Z] 13:09:12     INFO - TEST-START | /webdriver/tests/classic/element_click/bubbling.py
[task 2024-07-16T13:09:12.761Z] 13:09:12     INFO - STDOUT: ============================= test session starts ==============================
[task 2024-07-16T13:09:12.762Z] 13:09:12     INFO - STDOUT: platform linux -- Python 3.8.0, pytest-8.2.1, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2024-07-16T13:09:12.764Z] 13:09:12     INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2024-07-16T13:09:12.765Z] 13:09:12     INFO - STDOUT: plugins: asyncio-0.19.0
[task 2024-07-16T13:09:12.765Z] 13:09:12     INFO - STDOUT: asyncio: mode=strict
[task 2024-07-16T13:09:12.765Z] 13:09:12     INFO - STDOUT: collecting ... 
[task 2024-07-16T13:09:12.839Z] 13:09:12     INFO - STDOUT: collected 3 items
[task 2024-07-16T13:09:12.840Z] 13:09:12     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/element_click/bubbling.py::test_click_event_bubbles_to_parents 
[task 2024-07-16T13:09:12.842Z] 13:09:12     INFO - PID 16139 | 1721135352841	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmp7olpummq.mozrunner"] ... xternal_symbolizer_path=/builds/worker/workspace/build/application/firefox/llvm-symbolizer", "MOZ_WINDOW_OCCLUSION": "0"}}}}}
[task 2024-07-16T13:09:12.843Z] 13:09:12     INFO - PID 16139 | 1721135352841	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2024-07-16T13:09:12.843Z] 13:09:12     INFO - PID 16139 | 1721135352841	geckodriver::capabilities	DEBUG	Found version 129.0
[task 2024-07-16T13:09:12.844Z] 13:09:12     INFO - PID 16139 | 1721135352842	geckodriver::browser	DEBUG	Backing up prefs to "/tmp/tmp7olpummq.mozrunner/user.geckodriver_backup"
[task 2024-07-16T13:09:12.865Z] 13:09:12     INFO - PID 16139 | 1721135352864	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/tmp7olpummq.mozrunner" "-no-remote"
[task 2024-07-16T13:09:12.865Z] 13:09:12     INFO - PID 16139 | 1721135352864	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2024-07-16T13:09:12.866Z] 13:09:12     INFO - PID 16139 | 1721135352864	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:12.867Z] 13:09:12     INFO - PID 16139 | 1721135352864	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:12.911Z] 13:09:12     INFO - PID 16139 | *** You are running in headless mode.
[task 2024-07-16T13:09:12.965Z] 13:09:12     INFO - PID 16139 | 1721135352964	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:12.967Z] 13:09:12     INFO - PID 16139 | 1721135352964	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.066Z] 13:09:13     INFO - PID 16139 | 1721135353065	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.066Z] 13:09:13     INFO - PID 16139 | 1721135353065	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.166Z] 13:09:13     INFO - PID 16139 | 1721135353165	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.166Z] 13:09:13     INFO - PID 16139 | 1721135353165	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.266Z] 13:09:13     INFO - PID 16139 | 1721135353265	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.267Z] 13:09:13     INFO - PID 16139 | 1721135353265	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.366Z] 13:09:13     INFO - PID 16139 | 1721135353365	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.367Z] 13:09:13     INFO - PID 16139 | 1721135353365	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.466Z] 13:09:13     INFO - PID 16139 | 1721135353465	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.467Z] 13:09:13     INFO - PID 16139 | 1721135353465	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.566Z] 13:09:13     INFO - PID 16139 | 1721135353565	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.567Z] 13:09:13     INFO - PID 16139 | 1721135353565	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.666Z] 13:09:13     INFO - PID 16139 | 1721135353665	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.667Z] 13:09:13     INFO - PID 16139 | 1721135353665	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.767Z] 13:09:13     INFO - PID 16139 | 1721135353765	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.767Z] 13:09:13     INFO - PID 16139 | 1721135353766	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.867Z] 13:09:13     INFO - PID 16139 | 1721135353866	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.867Z] 13:09:13     INFO - PID 16139 | 1721135353866	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:13.967Z] 13:09:13     INFO - PID 16139 | 1721135353966	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:13.967Z] 13:09:13     INFO - PID 16139 | 1721135353966	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:14.067Z] 13:09:14     INFO - PID 16139 | 1721135354066	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:14.067Z] 13:09:14     INFO - PID 16139 | 1721135354066	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:14.167Z] 13:09:14     INFO - PID 16139 | 1721135354166	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:14.168Z] 13:09:14     INFO - PID 16139 | 1721135354166	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:14.230Z] 13:09:14     INFO - PID 16139 | 1721135354229	Marionette	INFO	Marionette enabled
[task 2024-07-16T13:09:14.235Z] 13:09:14     INFO - PID 16139 | 1721135354234	RemoteAgent	DEBUG	Setting recommended pref browser.contentblocking.introCount to 99
[task 2024-07-16T13:09:14.237Z] 13:09:14     INFO - PID 16139 | 1721135354236	RemoteAgent	DEBUG	Setting recommended pref browser.search.update to false
[task 2024-07-16T13:09:14.237Z] 13:09:14     INFO - PID 16139 | 1721135354236	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.remote.unloadDelayMs to 0
[task 2024-07-16T13:09:14.238Z] 13:09:14     INFO - PID 16139 | 1721135354237	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.warnOnClose to false
[task 2024-07-16T13:09:14.239Z] 13:09:14     INFO - PID 16139 | 1721135354237	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.warnOnCloseOtherTabs to false
[task 2024-07-16T13:09:14.239Z] 13:09:14     INFO - PID 16139 | 1721135354238	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.warnOnOpen to false
[task 2024-07-16T13:09:14.240Z] 13:09:14     INFO - PID 16139 | 1721135354238	RemoteAgent	DEBUG	Setting recommended pref browser.usedOnWindows10.introURL to
[task 2024-07-16T13:09:14.241Z] 13:09:14     INFO - PID 16139 | 1721135354239	RemoteAgent	DEBUG	Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
[task 2024-07-16T13:09:14.241Z] 13:09:14     INFO - PID 16139 | 1721135354240	RemoteAgent	DEBUG	Setting recommended pref dom.navigation.locationChangeRateLimit.count to 0
[task 2024-07-16T13:09:14.243Z] 13:09:14     INFO - PID 16139 | 1721135354241	RemoteAgent	DEBUG	Setting recommended pref dom.screenorientation.allow-lock to true
[task 2024-07-16T13:09:14.243Z] 13:09:14     INFO - PID 16139 | 1721135354242	RemoteAgent	DEBUG	Setting recommended pref network.connectivity-service.enabled to false
[task 2024-07-16T13:09:14.244Z] 13:09:14     INFO - PID 16139 | 1721135354243	RemoteAgent	DEBUG	Setting recommended pref privacy.trackingprotection.enabled to false
[task 2024-07-16T13:09:14.245Z] 13:09:14     INFO - PID 16139 | 1721135354243	RemoteAgent	DEBUG	Setting recommended pref remote.prefs.recommended.applied to true
[task 2024-07-16T13:09:14.245Z] 13:09:14     INFO - PID 16139 | 1721135354244	RemoteAgent	DEBUG	Setting recommended pref security.fileuri.strict_origin_policy to false
[task 2024-07-16T13:09:14.246Z] 13:09:14     INFO - PID 16139 | 1721135354244	RemoteAgent	DEBUG	Setting recommended pref signon.autofillForms to false
[task 2024-07-16T13:09:14.246Z] 13:09:14     INFO - PID 16139 | 1721135354244	RemoteAgent	DEBUG	Setting recommended pref widget.windows.window_occlusion_tracking.enabled to false
[task 2024-07-16T13:09:14.247Z] 13:09:14     INFO - PID 16139 | 1721135354247	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-07-16T13:09:14.267Z] 13:09:14     INFO - PID 16139 | 1721135354266	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:14.268Z] 13:09:14     INFO - PID 16139 | 1721135354266	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:14.367Z] 13:09:14     INFO - PID 16139 | 1721135354366	geckodriver::browser	TRACE	Failed to open /tmp/tmp7olpummq.mozrunner/MarionetteActivePort
[task 2024-07-16T13:09:14.368Z] 13:09:14     INFO - PID 16139 | 1721135354366	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-07-16T13:09:14.450Z] 13:09:14     INFO - PID 16139 | 1721135354449	Marionette	INFO	Listening on port 35572
[task 2024-07-16T13:09:14.452Z] 13:09:14     INFO - PID 16139 | 1721135354451	Marionette	DEBUG	Marionette is listening
[task 2024-07-16T13:09:14.467Z] 13:09:14     INFO - PID 16139 | Read port: 35572
[task 2024-07-16T13:09:14.534Z] 13:09:14     INFO - PID 16139 | 1721135354533	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:34350
[task 2024-07-16T13:09:14.544Z] 13:09:14     INFO - PID 16139 | 1721135354543	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:35572.
[task 2024-07-16T13:09:15.132Z] 13:09:15     INFO - PID 16139 | 1721135355131	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{}]
[task 2024-07-16T13:09:15.144Z] 13:09:15     INFO - PID 16139 | 1721135355143	Marionette	DEBUG	Waiting for initial application window
[task 2024-07-16T13:09:16.716Z] 13:09:16     INFO - PID 16139 | Crash Annotation GraphicsCriticalError: |[0][GFX1-]: RenderCompositorSWGL failed mapping default framebuffer, no dt (t=3.8403) [GFX1-]: RenderCompositorSWGL failed mapping default framebuffer, no dt
[task 2024-07-16T13:09:16.909Z] 13:09:16     INFO - PID 16139 | console.error: ({})
[task 2024-07-16T13:09:21.685Z] 13:09:21     INFO - PID 16139 | 1721135361684	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2024-07-16T13:09:21.706Z] 13:09:21     INFO - PID 16139 | 1721135361705	RemoteAgent	TRACE	[3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2024-07-16T13:09:21.708Z] 13:09:21     INFO - PID 16139 | 1721135361706	RemoteAgent	TRACE	[3] ProgressListener Document already loading about:blank
[task 2024-07-16T13:09:21.709Z] 13:09:21     INFO - PID 16139 | 1721135361707	RemoteAgent	TRACE	[3] ProgressListener Loading state: isStart=true isStop=false status=0x0, loadType=0x1
[task 2024-07-16T13:09:21.710Z] 13:09:21     INFO - PID 16139 | 1721135361707	RemoteAgent	TRACE	[3] ProgressListener Started loading about:blank
[task 2024-07-16T13:09:21.710Z] 13:09:21     INFO - PID 16139 | 1721135361707	RemoteAgent	TRACE	[3] Wait for initial navigation: isInitial=true, isLoadingDocument=true
[task 2024-07-16T13:09:21.726Z] 13:09:21     INFO - PID 16139 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-07-16T13:09:21.727Z] 13:09:21     INFO - PID 16139 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-07-16T13:12:37.515Z] 13:12:37     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/element_click/bubbling.py | expected OK
[task 2024-07-16T13:12:37.515Z] 13:12:37     INFO - TEST-INFO took 205003ms
[task 2024-07-16T13:12:43.559Z] 13:12:43     INFO - Closing logging queue
[task 2024-07-16T13:12:43.559Z] 13:12:43     INFO - queue closed
[task 2024-07-16T13:12:43.561Z] 13:12:43     INFO - Starting WebDriver: /builds/worker/fetches/geckodriver --host 127.0.0.1 --port 0 -vv --binary /builds/worker/workspace/build/application/firefox/firefox
[task 2024-07-16T13:12:43.672Z] 13:12:43     INFO - PID 16332 | 1721135563570	geckodriver	INFO	Listening on 127.0.0.1:39204
[task 2024-07-16T13:12:43.672Z] 13:12:43     INFO - Webdriver started successfully.
[task 2024-07-16T13:12:43.673Z] 13:12:43     INFO - Starting runner
[task 2024-07-16T13:12:44.001Z] 13:12:44     INFO - TEST-START | /webdriver/tests/classic/element_click/center_point.py

The initial about:blank page doesn't seem to finish loading. Lets observe for now.

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: NEW → RESOLVED
Closed: 7 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.