Closed
Bug 1806043
Opened 2 years ago
Closed 2 years ago
Intermittent Android TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/get_tree/root.py | expected OK (Network request failed)
Categories
(Remote Protocol :: WebDriver BiDi, defect, P5)
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
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=399839553&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CcQMwdKiRtKpJu3iyURo8g/runs/0/artifacts/public/logs/live_backing.log
[task 2022-12-16T01:03:10.358Z] 01:03:10 INFO - TEST-START | /webdriver/tests/bidi/browsing_context/get_tree/root.py
[task 2022-12-16T01:03:10.359Z] 01:03:10 INFO - PID 2377 | 1671152590352 mozdevice TRACE execute_host_command: << ""
[task 2022-12-16T01:03:10.359Z] 01:03:10 INFO - PID 2377 | 1671152590352 geckodriver::android DEBUG WebSocket port forward (9222 -> 9222) stopped
[task 2022-12-16T01:03:10.360Z] 01:03:10 INFO - PID 2377 | 1671152590352 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:10.413Z] 01:03:10 INFO - STDOUT: ============================= test session starts ==============================
[task 2022-12-16T01:03:10.414Z] 01:03:10 INFO - STDOUT: platform linux -- Python 3.6.9, pytest-7.0.1, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2022-12-16T01:03:10.415Z] 01:03:10 INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2022-12-16T01:03:10.415Z] 01:03:10 INFO - STDOUT: plugins: asyncio-0.14.0
[task 2022-12-16T01:03:10.416Z] 01:03:10 INFO - STDOUT: collecting ...
[task 2022-12-16T01:03:10.424Z] 01:03:10 INFO - STDOUT: collected 5 items
[task 2022-12-16T01:03:10.425Z] 01:03:10 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/get_tree/root.py::test_null[tab]
...
[task 2022-12-16T01:03:15.866Z] 01:03:15 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/get_tree/root.py::test_null[window]
[task 2022-12-16T01:03:15.993Z] 01:03:15 INFO - STDOUT: FAILED
[task 2022-12-16T01:03:16.001Z] 01:03:16 INFO - PID 2377 | 1671152596000 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/timeouts {"implicit": 0}
[task 2022-12-16T01:03:16.008Z] 01:03:16 INFO - PID 2377 | 1671152596007 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:16.009Z] 01:03:16 INFO - PID 2377 | 1671152596008 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/timeouts {"pageLoad": 300000}
[task 2022-12-16T01:03:16.016Z] 01:03:16 INFO - PID 2377 | 1671152596016 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:16.017Z] 01:03:16 INFO - PID 2377 | 1671152596017 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/timeouts {"script": 30000}
[task 2022-12-16T01:03:16.024Z] 01:03:16 INFO - PID 2377 | 1671152596024 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:16.025Z] 01:03:16 INFO - PID 2377 | 1671152596025 webdriver::server DEBUG -> GET /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window
[task 2022-12-16T01:03:16.032Z] 01:03:16 INFO - PID 2377 | 1671152596031 webdriver::server DEBUG <- 200 OK {"value":"0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"}
[task 2022-12-16T01:03:16.033Z] 01:03:16 INFO - PID 2377 | 1671152596032 webdriver::server DEBUG -> GET /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window
[task 2022-12-16T01:03:16.039Z] 01:03:16 INFO - PID 2377 | 1671152596038 webdriver::server DEBUG <- 200 OK {"value":"0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"}
[task 2022-12-16T01:03:16.040Z] 01:03:16 INFO - PID 2377 | 1671152596039 webdriver::server DEBUG -> GET /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window/handles
[task 2022-12-16T01:03:16.051Z] 01:03:16 INFO - PID 2377 | 1671152596050 webdriver::server DEBUG <- 200 OK {"value":["0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"]}
[task 2022-12-16T01:03:16.052Z] 01:03:16 INFO - PID 2377 | 1671152596051 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window {"handle": "0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"}
[task 2022-12-16T01:03:16.061Z] 01:03:16 INFO - PID 2377 | 1671152596060 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:16.062Z] 01:03:16 INFO - PID 2377 | 1671152596061 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/alert/dismiss {}
[task 2022-12-16T01:03:16.070Z] 01:03:16 INFO - PID 2377 | 1671152596069 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:250:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"}}
[task 2022-12-16T01:03:16.071Z] 01:03:16 INFO - PID 2377 | 1671152596070 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window {"handle": "0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"}
[task 2022-12-16T01:03:16.080Z] 01:03:16 INFO - PID 2377 | 1671152596079 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:16.081Z] 01:03:16 INFO - PID 2377 | 1671152596080 webdriver::server DEBUG -> GET /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window
[task 2022-12-16T01:03:16.087Z] 01:03:16 INFO - PID 2377 | 1671152596087 webdriver::server DEBUG <- 200 OK {"value":"0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"}
[task 2022-12-16T01:03:16.088Z] 01:03:16 INFO - PID 2377 | 1671152596087 webdriver::server DEBUG -> GET /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window/handles
[task 2022-12-16T01:03:16.096Z] 01:03:16 INFO - PID 2377 | 1671152596095 webdriver::server DEBUG <- 200 OK {"value":["0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"]}
[task 2022-12-16T01:03:16.097Z] 01:03:16 INFO - PID 2377 | 1671152596096 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/window {"handle": "0fc3cdbd-4b8a-491e-86a9-7ce42337a93d"}
[task 2022-12-16T01:03:16.105Z] 01:03:16 INFO - PID 2377 | 1671152596104 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:16.106Z] 01:03:16 INFO - PID 2377 | 1671152596105 webdriver::server DEBUG -> POST /session/c0738dce-e07f-4549-9486-ecf6746fc9c2/frame {"id": null}
[task 2022-12-16T01:03:16.121Z] 01:03:16 INFO - PID 2377 | 1671152596120 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-12-16T01:03:16.124Z] 01:03:16 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/get_tree/root.py::test_top_level_context[tab]
[task 2022-12-16T01:04:30.359Z] 01:04:30 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/get_tree/root.py | expected OK
[task 2022-12-16T01:04:30.359Z] 01:04:30 INFO - TEST-INFO took 80002ms
|
||
Comment 1•2 years ago
|
||
That's a failure on Android. In the adb log the following can be seen:
12-16 01:03:21.131 8012 8027 I Gecko : 1671152601131 RemoteAgent TRACE MessageHandler WINDOW_GLOBAL for session c0738dce-e07f-4549-9486-ecf6746fc9c2 is being destroyed
12-16 01:03:21.132 8012 8027 I Gecko : 1671152601132 RemoteAgent TRACE Unregistered MessageHandler WINDOW_GLOBAL for session c0738dce-e07f-4549-9486-ecf6746fc9c2
12-16 01:03:22.058 7984 7999 I Gecko : nsWindow 0x7e7a4b3d7a00 destructor
12-16 01:04:12.180 7984 8039 W rkv::backend::impl_safe::environment: `load_ratio()` is irrelevant for this storage backend.
12-16 01:04:12.212 7984 7999 I Gecko : console.error: (new Error("Polling for changes failed: NetworkError: Network request failed.", "resource://services-settings/remote-settings.js", 334))
12-16 01:04:12.212 7984 7999 E GeckoConsole: [JavaScript Error: "Error: Polling for changes failed: NetworkError: Network request failed."]
12-16 01:04:29.493 7984 7999 I Gecko : 1671152669493 WebDriver BiDi DEBUG Unregistered session handler: /session/c0738dce-e07f-4549-9486-ecf6746fc9c2
Maybe there was a network error at that time causing geckodriver to not correctly send the next command to the device.
Lets observe.
OS: Unspecified → Android
Hardware: Unspecified → All
Summary: Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/get_tree/root.py | expected OK → Intermittent Android TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/get_tree/root.py | expected OK (Network request failed)
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•