Closed Bug 1904818 Opened 7 months ago Closed 4 months ago

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

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

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


[task 2024-06-26T11:39:55.620Z] 11:39:55     INFO - STDOUT: PASSED
[task 2024-06-26T11:39:55.620Z] 11:39:55     INFO - PID 1770 | 1719401995619	webdriver::server	DEBUG	-> DELETE /session/3017873d-fd21-446c-860a-f046fe4e431e/actions
[task 2024-06-26T11:39:55.634Z] 11:39:55     INFO - PID 1770 | 1719401995633	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.636Z] 11:39:55     INFO - PID 1770 | 1719401995635	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"implicit": 0}
[task 2024-06-26T11:39:55.645Z] 11:39:55     INFO - PID 1770 | 1719401995643	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.646Z] 11:39:55     INFO - PID 1770 | 1719401995645	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"pageLoad": 300000}
[task 2024-06-26T11:39:55.654Z] 11:39:55     INFO - PID 1770 | 1719401995652	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.655Z] 11:39:55     INFO - PID 1770 | 1719401995654	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"script": 30000}
[task 2024-06-26T11:39:55.665Z] 11:39:55     INFO - PID 1770 | 1719401995663	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.667Z] 11:39:55     INFO - PID 1770 | 1719401995666	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window
[task 2024-06-26T11:39:55.676Z] 11:39:55     INFO - PID 1770 | 1719401995675	webdriver::server	DEBUG	<- 200 OK {"value":"a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.677Z] 11:39:55     INFO - PID 1770 | 1719401995676	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window
[task 2024-06-26T11:39:55.686Z] 11:39:55     INFO - PID 1770 | 1719401995684	webdriver::server	DEBUG	<- 200 OK {"value":"a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.687Z] 11:39:55     INFO - PID 1770 | 1719401995686	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window/handles
[task 2024-06-26T11:39:55.697Z] 11:39:55     INFO - PID 1770 | 1719401995696	webdriver::server	DEBUG	<- 200 OK {"value":["a197b56a-406c-470d-8b54-ca107921c4a9"]}
[task 2024-06-26T11:39:55.698Z] 11:39:55     INFO - PID 1770 | 1719401995697	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/window {"handle": "a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.709Z] 11:39:55     INFO - PID 1770 | 1719401995708	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.712Z] 11:39:55     INFO - PID 1770 | 1719401995710	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/alert/dismiss {}
[task 2024-06-26T11:39:55.724Z] 11:39:55     INFO - PID 1770 | 1719401995722	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:263:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:494:20\n"}}
[task 2024-06-26T11:39:55.725Z] 11:39:55     INFO - PID 1770 | 1719401995723	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/window {"handle": "a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.735Z] 11:39:55     INFO - PID 1770 | 1719401995733	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.736Z] 11:39:55     INFO - PID 1770 | 1719401995735	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window
[task 2024-06-26T11:39:55.746Z] 11:39:55     INFO - PID 1770 | 1719401995744	webdriver::server	DEBUG	<- 200 OK {"value":"a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.747Z] 11:39:55     INFO - PID 1770 | 1719401995746	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window/handles
[task 2024-06-26T11:39:55.756Z] 11:39:55     INFO - PID 1770 | 1719401995755	webdriver::server	DEBUG	<- 200 OK {"value":["a197b56a-406c-470d-8b54-ca107921c4a9"]}
[task 2024-06-26T11:39:55.757Z] 11:39:55     INFO - PID 1770 | 1719401995756	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/window {"handle": "a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.767Z] 11:39:55     INFO - PID 1770 | 1719401995765	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.769Z] 11:39:55     INFO - PID 1770 | 1719401995767	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/frame {"id": null}
[task 2024-06-26T11:39:55.784Z] 11:39:55     INFO - PID 1770 | 1719401995783	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.789Z] 11:39:55     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/perform_actions/invalid.py::test_wheel_action_scroll_origin_invalid_type[None] 
[task 2024-06-26T11:39:55.791Z] 11:39:55     INFO - PID 1770 | 1719401995789	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"implicit": 0}
[task 2024-06-26T11:39:55.801Z] 11:39:55     INFO - PID 1770 | 1719401995800	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.803Z] 11:39:55     INFO - PID 1770 | 1719401995802	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"pageLoad": 12000}
[task 2024-06-26T11:39:55.814Z] 11:39:55     INFO - PID 1770 | 1719401995812	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.816Z] 11:39:55     INFO - PID 1770 | 1719401995815	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"script": 4000}
[task 2024-06-26T11:39:55.826Z] 11:39:55     INFO - PID 1770 | 1719401995824	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.829Z] 11:39:55     INFO - PID 1770 | 1719401995827	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/actions {"actions": [{"type": "wheel", "id": "foo", "actions": [{"type": "scroll", "x": 0, "y": 0, "deltaX": 0, "deltaY": 0, "origin": null}]}]}
[task 2024-06-26T11:39:55.830Z] 11:39:55     INFO - PID 1770 | 1719401995827	webdriver::server	DEBUG	<- 400 Bad Request {"value":{"error":"invalid argument","message":"data did not match any variant of untagged enum WheelActionItem at line 1 column 134","stacktrace":""}}
[task 2024-06-26T11:39:55.831Z] 11:39:55     INFO - STDOUT: PASSED
[task 2024-06-26T11:39:55.831Z] 11:39:55     INFO - PID 1770 | 1719401995830	webdriver::server	DEBUG	-> DELETE /session/3017873d-fd21-446c-860a-f046fe4e431e/actions
[task 2024-06-26T11:39:55.844Z] 11:39:55     INFO - PID 1770 | 1719401995842	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.845Z] 11:39:55     INFO - PID 1770 | 1719401995844	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"implicit": 0}
[task 2024-06-26T11:39:55.855Z] 11:39:55     INFO - PID 1770 | 1719401995854	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.856Z] 11:39:55     INFO - PID 1770 | 1719401995855	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"pageLoad": 300000}
[task 2024-06-26T11:39:55.864Z] 11:39:55     INFO - PID 1770 | 1719401995862	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.865Z] 11:39:55     INFO - PID 1770 | 1719401995864	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/timeouts {"script": 30000}
[task 2024-06-26T11:39:55.873Z] 11:39:55     INFO - PID 1770 | 1719401995872	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.874Z] 11:39:55     INFO - PID 1770 | 1719401995873	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window
[task 2024-06-26T11:39:55.883Z] 11:39:55     INFO - PID 1770 | 1719401995882	webdriver::server	DEBUG	<- 200 OK {"value":"a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.884Z] 11:39:55     INFO - PID 1770 | 1719401995883	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window
[task 2024-06-26T11:39:55.892Z] 11:39:55     INFO - PID 1770 | 1719401995890	webdriver::server	DEBUG	<- 200 OK {"value":"a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.892Z] 11:39:55     INFO - PID 1770 | 1719401995892	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window/handles
[task 2024-06-26T11:39:55.902Z] 11:39:55     INFO - PID 1770 | 1719401995901	webdriver::server	DEBUG	<- 200 OK {"value":["a197b56a-406c-470d-8b54-ca107921c4a9"]}
[task 2024-06-26T11:39:55.903Z] 11:39:55     INFO - PID 1770 | 1719401995902	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/window {"handle": "a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.913Z] 11:39:55     INFO - PID 1770 | 1719401995912	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.914Z] 11:39:55     INFO - PID 1770 | 1719401995913	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/alert/dismiss {}
[task 2024-06-26T11:39:55.925Z] 11:39:55     INFO - PID 1770 | 1719401995923	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:263:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:494:20\n"}}
[task 2024-06-26T11:39:55.926Z] 11:39:55     INFO - PID 1770 | 1719401995925	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/window {"handle": "a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.936Z] 11:39:55     INFO - PID 1770 | 1719401995935	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.937Z] 11:39:55     INFO - PID 1770 | 1719401995936	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window
[task 2024-06-26T11:39:55.946Z] 11:39:55     INFO - PID 1770 | 1719401995944	webdriver::server	DEBUG	<- 200 OK {"value":"a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.948Z] 11:39:55     INFO - PID 1770 | 1719401995946	webdriver::server	DEBUG	-> GET /session/3017873d-fd21-446c-860a-f046fe4e431e/window/handles
[task 2024-06-26T11:39:55.957Z] 11:39:55     INFO - PID 1770 | 1719401995956	webdriver::server	DEBUG	<- 200 OK {"value":["a197b56a-406c-470d-8b54-ca107921c4a9"]}
[task 2024-06-26T11:39:55.958Z] 11:39:55     INFO - PID 1770 | 1719401995957	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/window {"handle": "a197b56a-406c-470d-8b54-ca107921c4a9"}
[task 2024-06-26T11:39:55.967Z] 11:39:55     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/perform_actions/invalid.py | expected OK
[task 2024-06-26T11:39:55.967Z] 11:39:55     INFO - TEST-INFO took 105008ms
[task 2024-06-26T11:39:55.974Z] 11:39:55     INFO - PID 1770 | 1719401995966	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-06-26T11:39:55.975Z] 11:39:55     INFO - PID 1770 | 1719401995967	webdriver::server	DEBUG	-> POST /session/3017873d-fd21-446c-860a-f046fe4e431e/frame {"id": null}
[task 2024-06-26T11:39:56.375Z] 11:39:56     INFO - Closing logging queue
[task 2024-06-26T11:39:56.375Z] 11:39:56     INFO - queue closed
[task 2024-06-26T11:39:56.554Z] 11:39:56     INFO - Starting WebDriver: /builds/worker/fetches/geckodriver --host 127.0.0.1 --port 0 -vv
[task 2024-06-26T11:39:56.668Z] 11:39:56     INFO - PID 1845 | 1719401996565	geckodriver	INFO	Listening on 127.0.0.1:39629
[task 2024-06-26T11:39:56.669Z] 11:39:56     INFO - Webdriver started successfully.
[task 2024-06-26T11:39:56.669Z] 11:39:56     INFO - Starting runner
[task 2024-06-26T11:39:57.101Z] 11:39:57     INFO - TEST-START | /webdriver/tests/classic/perform_actions/key.py
Status: NEW → RESOLVED
Closed: 7 months ago
Duplicate of bug: 1841486
Resolution: --- → DUPLICATE

Sorry, the other bug is already fixed. Lets use that bug for tracking the timeout of the test.

Status: RESOLVED → REOPENED
No longer duplicate of bug: 1841486
Resolution: DUPLICATE → ---
Status: REOPENED → RESOLVED
Closed: 7 months ago4 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.