Closed Bug 1786534 Opened 3 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/close/invalid.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1784870

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

[task 2022-08-23T11:59:46.779Z] 11:59:46     INFO - TEST-START | /webdriver/tests/bidi/browsing_context/close/invalid.py
[task 2022-08-23T11:59:46.856Z] 11:59:46     INFO - STDOUT: ============================= test session starts =============================
[task 2022-08-23T11:59:46.859Z] 11:59:46     INFO - STDOUT: platform win32 -- Python 3.9.5, pytest-7.0.1, pluggy-unknown -- Z:\task_166124949659916\build\venv\Scripts\python.exe
[task 2022-08-23T11:59:46.860Z] 11:59:46     INFO - STDOUT: rootdir: Z:\task_166124949659916\build
[task 2022-08-23T11:59:46.861Z] 11:59:46     INFO - STDOUT: plugins: asyncio-0.14.0
[task 2022-08-23T11:59:46.862Z] 11:59:46     INFO - STDOUT: collecting ... 
[task 2022-08-23T11:59:46.889Z] 11:59:46     INFO - STDOUT: collected 7 items
[task 2022-08-23T11:59:46.896Z] 11:59:46     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/close/invalid.py::test_params_context_invalid_type[None] 
[task 2022-08-23T11:59:46.899Z] 11:59:46     INFO - PID 6280 | 1661255986897	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "C:\\Users\\task_166124949659916\\AppData\\Local\\Temp\\tmp__d71lxp.mozrunner"], "binary": "Z:\\task_166124949659916\\build\\application\\firefox\\firefox.exe"}, "webSocketUrl": true}}}
[task 2022-08-23T11:59:46.900Z] 11:59:46     INFO - PID 6280 | 1661255986897	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2022-08-23T11:59:46.900Z] 11:59:46     INFO - PID 6280 | 1661255986898	geckodriver::capabilities	DEBUG	Found version 106.0a1
[task 2022-08-23T11:59:46.901Z] 11:59:46     INFO - PID 6280 | 1661255986899	geckodriver::browser	DEBUG	Backing up prefs to "C:\\Users\\task_166124949659916\\AppData\\Local\\Temp\\tmp__d71lxp.mozrunner\\user.geckodriver_backup"
[task 2022-08-23T11:59:46.912Z] 11:59:46     INFO - PID 6280 | 1661255986910	mozrunner::runner	INFO	Running command: "Z:\\task_166124949659916\\build\\application\\firefox\\firefox.exe" "--marionette" "--profile" "C:\\Users\\task_166124949659916\\AppData\\Local\\Temp\\tmp__d71lxp.mozrunner" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-no-remote"
[task 2022-08-23T11:59:46.913Z] 11:59:46     INFO - PID 6280 | 1661255986912	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2022-08-23T11:59:46.914Z] 11:59:46     INFO - PID 6280 | 1661255986912	geckodriver::browser	TRACE	Failed to open C:\Users\task_166124949659916\AppData\Local\Temp\tmp__d71lxp.mozrunner\MarionetteActivePort
[task 2022-08-23T11:59:46.914Z] 11:59:46     INFO - PID 6280 | 1661255986913	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2022-08-23T11:59:47.019Z] 11:59:47     INFO - PID 6280 | 1661255987019	geckodriver::browser	TRACE	Failed to open C:\Users\task_166124949659916\AppData\Local\Temp\tmp__d71lxp.mozrunner\MarionetteActivePort
[task 2022-08-23T11:59:47.021Z] 11:59:47     INFO - PID 6280 | 1661255987020	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2022-08-23T11:59:47.111Z] 11:59:47     INFO - PID 6280 | 1661255987124	RemoteAgent	DEBUG	WebDriver BiDi enabled
[task 2022-08-23T11:59:47.126Z] 11:59:47     INFO - PID 6280 | 1661255987125	RemoteAgent	DEBUG	CDP enabled
[task 2022-08-23T11:59:47.127Z] 11:59:47     INFO - PID 6280 | 1661255987125	Marionette	INFO	Marionette enabled
[task 2022-08-23T11:59:47.128Z] 11:59:47     INFO - PID 6280 | 1661255987126	Marionette	TRACE	Received observer notification final-ui-startup
[task 2022-08-23T11:59:47.129Z] 11:59:47     INFO - PID 6280 | 1661255987126	geckodriver::browser	TRACE	Failed to open C:\Users\task_166124949659916\AppData\Local\Temp\tmp__d71lxp.mozrunner\MarionetteActivePort
[task 2022-08-23T11:59:47.129Z] 11:59:47     INFO - PID 6280 | 1661255987126	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2022-08-23T11:59:47.130Z] 11:59:47     INFO - PID 6280 | 1661255987126	Marionette	INFO	Listening on port 50155
[task 2022-08-23T11:59:47.130Z] 11:59:47     INFO - PID 6280 | 1661255987127	Marionette	DEBUG	Marionette is listening
[task 2022-08-23T11:59:47.131Z] 11:59:47     INFO - PID 6280 | 1661255987127	RemoteAgent	TRACE	Received observer notification final-ui-startup
[task 2022-08-23T11:59:47.142Z] 11:59:47     INFO - PID 6280 | WebDriver BiDi listening on ws://127.0.0.1:9222
[task 2022-08-23T11:59:47.158Z] 11:59:47     INFO - PID 6280 | 1661255987156	CDP	DEBUG	Waiting for initial application window
[task 2022-08-23T11:59:47.232Z] 11:59:47     INFO - PID 6280 | Read port: 50155
[task 2022-08-23T11:59:47.242Z] 11:59:47     INFO - PID 6280 | 1661255987253	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:50156
[task 2022-08-23T11:59:47.265Z] 11:59:47     INFO - PID 6280 | 1661255987266	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:50155.
[task 2022-08-23T11:59:47.276Z] 11:59:47     INFO - PID 6280 | 1661255987275	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{"webSocketUrl":true}]
[task 2022-08-23T11:59:47.280Z] 11:59:47     INFO - PID 6280 | 1661255987280	WebDriver BiDi	DEBUG	Waiting for initial application window
[task 2022-08-23T11:59:48.139Z] 11:59:48     INFO - PID 6280 | *** You are running in background task mode. ***
[task 2022-08-23T11:59:48.141Z] 11:59:48     INFO - PID 6280 | *** You are running in headless mode.
[task 2022-08-23T11:59:48.234Z] 11:59:48     INFO - PID 6280 | console.error: "C:\\Users\\task_166124949659916\\AppData\\Local\\Temp\\tmp__d71lxp.mozrunner" "" 0 ".purge.bg_rm"
[task 2022-08-23T12:00:16.787Z] 12:00:16     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/close/invalid.py | expected OK
[task 2022-08-23T12:00:16.787Z] 12:00:16     INFO - TEST-INFO took 30007ms
[task 2022-08-23T12:00:17.211Z] 12:00:17     INFO - Closing logging queue
[task 2022-08-23T12:00:17.219Z] 12:00:17     INFO - queue closed
[task 2022-08-23T12:00:17.730Z] 12:00:17     INFO - PID 8396 | 1661256017233	geckodriver	INFO	Listening on 127.0.0.1:49883
[task 2022-08-23T12:00:17.733Z] 12:00:17     INFO - Starting runner
[task 2022-08-23T12:00:18.291Z] 12:00:18     INFO - TEST-START | /webdriver/tests/bidi/browsing_context/context_created/context_created.py
Status: NEW → RESOLVED
Closed: 3 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.