Closed Bug 1747004 Opened 3 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/create_alwaysMatch.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1630162

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2021-12-21T03:02:26.523Z] 03:02:26     INFO - STDOUT: PASSED
[task 2021-12-21T03:02:26.532Z] 03:02:26     INFO - PID 2708 | 1640055746523	webdriver::server	DEBUG	<- 200 OK {"value":{"sessionId":"183a5798-a5a9-4efe-a87d-861f4840e3a4","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"97.0a1","moz:accessibilityChecks":false,"moz:buildID":"20211221015257","moz:geckodriverVersion":"0.30.0","moz:headless":false,"moz:processID":7952,"moz:profile":"C:\\Users\\task_164005436095221\\AppData\\Local\\Temp\\tmp5pduhzku.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"pageLoadStrategy":"normal","platformName":"windows","platformVersion":"10.0","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
[task 2021-12-21T03:02:26.534Z] 03:02:26     INFO - PID 2708 | 1640055746527	webdriver::server	DEBUG	-> DELETE /session/183a5798-a5a9-4efe-a87d-861f4840e3a4
[task 2021-12-21T03:02:26.535Z] 03:02:26     INFO - PID 2708 | 1640055746528	Marionette	DEBUG	0 -> [0,2,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2021-12-21T03:02:26.537Z] 03:02:26     INFO - PID 2708 | 1640055746530	Marionette	INFO	Stopped listening on port 2828
[task 2021-12-21T03:02:26.599Z] 03:02:26     INFO - PID 2708 | 1640055746609	Marionette	TRACE	Received observer notification quit-application
[task 2021-12-21T03:02:26.612Z] 03:02:26     INFO - PID 2708 | 1640055746609	Marionette	TRACE	Received observer notification quit-application
[task 2021-12-21T03:02:26.613Z] 03:02:26     INFO - PID 2708 | 1640055746609	Marionette	DEBUG	Marionette stopped listening
[task 2021-12-21T03:02:26.618Z] 03:02:26     INFO - PID 2708 | 1640055746616	Marionette	DEBUG	0 <- [1,2,null,{"cause":"shutdown","forced":false}]
[task 2021-12-21T03:02:26.646Z] 03:02:26     INFO - PID 2708 | 1640055746644	webdriver::server	DEBUG	Teardown session
[task 2021-12-21T03:02:26.648Z] 03:02:26     INFO - PID 2708 | 1640055746649	Marionette	DEBUG	Closed connection 0
[task 2021-12-21T03:02:41.648Z] 03:02:41     INFO - PID 2708 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 446))
[task 2021-12-21T03:02:45.024Z] 03:02:45     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/create_alwaysMatch.py | expected OK
[task 2021-12-21T03:02:45.024Z] 03:02:45     INFO - TEST-INFO took 185004ms
[task 2021-12-21T03:02:52.054Z] 03:02:52     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/create_alwaysMatch.py::test_valid[strictFileInteractability-False] 
[task 2021-12-21T03:02:52.064Z] 03:02:52     INFO - STDOUT: ERROR
[task 2021-12-21T03:02:52.064Z] 03:02:52     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/create_alwaysMatch.py::test_valid[strictFileInteractability-None] 
[task 2021-12-21T03:02:52.065Z] 03:02:52     INFO - STDOUT: FAILED
[task 2021-12-21T03:02:52.065Z] 03:02:52     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/create_alwaysMatch.py::test_valid[unhandledPromptBehavior-dismiss] 
[task 2021-12-21T03:02:52.066Z] 03:02:52     INFO - STDOUT: FAILED
[task 2021-12-21T03:02:52.067Z] 03:02:52     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/create_alwaysMatch.py::test_valid[unhandledPromptBehavior-accept] 
[task 2021-12-21T03:02:52.067Z] 03:02:52     INFO - Closing logging queue
[task 2021-12-21T03:02:52.068Z] 03:02:52     INFO - queue closed
[task 2021-12-21T03:02:52.071Z] 03:02:52     INFO - Starting runner
[task 2021-12-21T03:02:53.694Z] 03:02:53     INFO - PID 2544 | 1640055773199	geckodriver	INFO	Listening on 127.0.0.1:62558
[task 2021-12-21T03:02:53.699Z] 03:02:53     INFO - WebDriver HTTP server listening at http://127.0.0.1:62558/
[task 2021-12-21T03:02:53.701Z] 03:02:53     INFO - TEST-START | /webdriver/tests/new_session/create_firstMatch.py

Slow shutdown of Firefox:

[task 2021-12-21T03:02:26.648Z] 03:02:26     INFO - PID 2708 | 1640055746649	Marionette	DEBUG	Closed connection 0
[task 2021-12-21T03:02:41.648Z] 03:02:41     INFO - PID 2708 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 446))
[task 2021-12-21T03:02:45.024Z] 03:02:45     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/create_alwaysMatch.py | expected OK
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.