Closed
Bug 1791102
Opened 2 years ago
Closed 2 years ago
Intermittent Android Wd TEST-UNEXPECTED-TIMEOUT | expected OK ("POST /session/%id/url")
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1791111
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=390623366&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FobPPGV-TNKDrD9lilqLUg/runs/0/artifacts/public/logs/live_backing.log
[task 2022-09-15T22:24:23.107Z] 22:24:23 INFO - PID 4094 | 1663280663106 webdriver::server DEBUG -> POST /session/5d90ecdf-d738-4c2f-be73-0bf59aaa900a/frame {"id": null}
[task 2022-09-15T22:24:23.141Z] 22:24:23 INFO - PID 4094 | 1663280663140 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-15T22:24:23.144Z] 22:24:23 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/permissions/set.py::test_set_to_state_cross_realm[capabilities0-realmSetting1-prompt]
[task 2022-09-15T22:24:23.145Z] 22:24:23 INFO - PID 4094 | 1663280663143 webdriver::server DEBUG -> POST /session/5d90ecdf-d738-4c2f-be73-0bf59aaa900a/url {"url": "https://web-platform.test:8443/common/blank.html"}
[task 2022-09-15T22:24:23.230Z] 22:24:23 INFO - PID 4094 | 1663280663229 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-15T22:24:23.231Z] 22:24:23 INFO - PID 4094 | 1663280663230 webdriver::server DEBUG -> GET /session/5d90ecdf-d738-4c2f-be73-0bf59aaa900a/window
[task 2022-09-15T22:24:23.242Z] 22:24:23 INFO - PID 4094 | 1663280663241 webdriver::server DEBUG <- 200 OK {"value":"27762280-029e-439b-93d7-8271f35c2616"}
[task 2022-09-15T22:24:23.243Z] 22:24:23 INFO - PID 4094 | 1663280663242 webdriver::server DEBUG -> POST /session/5d90ecdf-d738-4c2f-be73-0bf59aaa900a/window/new {"type": "tab"}
[task 2022-09-15T22:24:23.658Z] 22:24:23 INFO - PID 4094 | 1663280663657 webdriver::server DEBUG <- 200 OK {"value":{"handle":"db484ee4-dfa8-4654-830f-0130de5663b5","type":"tab"}}
[task 2022-09-15T22:24:23.659Z] 22:24:23 INFO - PID 4094 | 1663280663659 webdriver::server DEBUG -> POST /session/5d90ecdf-d738-4c2f-be73-0bf59aaa900a/window {"handle": "db484ee4-dfa8-4654-830f-0130de5663b5"}
[task 2022-09-15T22:24:23.667Z] 22:24:23 INFO - PID 4094 | 1663280663666 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-15T22:24:23.668Z] 22:24:23 INFO - PID 4094 | 1663280663667 webdriver::server DEBUG -> POST /session/5d90ecdf-d738-4c2f-be73-0bf59aaa900a/url {"url": "https://web-platform.test:8443/common/blank.html"}
[task 2022-09-15T22:24:23.714Z] 22:24:23 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/permissions/set.py | expected OK
[task 2022-09-15T22:24:23.714Z] 22:24:23 INFO - TEST-INFO took 80004ms
[task 2022-09-15T22:24:23.715Z] 22:24:23 INFO - Restarting browser for new test group
[task 2022-09-15T22:24:23.861Z] 22:24:23 INFO - Closing logging queue
[task 2022-09-15T22:24:23.862Z] 22:24:23 INFO - queue closed
[task 2022-09-15T22:24:23.945Z] 22:24:23 INFO - PID 4192 | 1663280663944 geckodriver INFO Listening on 127.0.0.1:33208
[task 2022-09-15T22:24:23.946Z] 22:24:23 INFO - Starting runner
[task 2022-09-15T22:24:24.188Z] 22:24:24 INFO - TEST-START | /webdriver/tests/release_actions/release.py
|
||
Comment 1•2 years ago
|
||
Hello Henrik, could you take a look at this Wd fail? It seems the ../window POST passes with OK but then fails when it's POST ../url
Flags: needinfo?(hskupin)
|
||
Comment 2•2 years ago
|
||
There are also failures like this one
[task 2022-09-17T07:20:00.595Z] 07:20:00 INFO - STDOUT: FAILED
[task 2022-09-17T07:20:00.596Z] 07:20:00 INFO - PID 4386 | 1663399200594 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/timeouts {"implicit": 0}
[task 2022-09-17T07:20:00.603Z] 07:20:00 INFO - PID 4386 | 1663399200602 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.604Z] 07:20:00 INFO - PID 4386 | 1663399200603 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/timeouts {"pageLoad": 300000}
[task 2022-09-17T07:20:00.616Z] 07:20:00 INFO - PID 4386 | 1663399200614 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.617Z] 07:20:00 INFO - PID 4386 | 1663399200616 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/timeouts {"script": 30000}
[task 2022-09-17T07:20:00.623Z] 07:20:00 INFO - PID 4386 | 1663399200622 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.624Z] 07:20:00 INFO - PID 4386 | 1663399200623 webdriver::server DEBUG -> GET /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window
[task 2022-09-17T07:20:00.630Z] 07:20:00 INFO - PID 4386 | 1663399200629 webdriver::server DEBUG <- 200 OK {"value":"49363c9b-26f2-46e1-a7d8-2740b87724ee"}
[task 2022-09-17T07:20:00.631Z] 07:20:00 INFO - PID 4386 | 1663399200630 webdriver::server DEBUG -> GET /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window
[task 2022-09-17T07:20:00.638Z] 07:20:00 INFO - PID 4386 | 1663399200637 webdriver::server DEBUG <- 200 OK {"value":"49363c9b-26f2-46e1-a7d8-2740b87724ee"}
[task 2022-09-17T07:20:00.638Z] 07:20:00 INFO - PID 4386 | 1663399200638 webdriver::server DEBUG -> GET /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window/handles
[task 2022-09-17T07:20:00.645Z] 07:20:00 INFO - PID 4386 | 1663399200644 webdriver::server DEBUG <- 200 OK {"value":["6e53da39-237b-424a-b290-a77418e9850d","49363c9b-26f2-46e1-a7d8-2740b87724ee"]}
[task 2022-09-17T07:20:00.646Z] 07:20:00 INFO - PID 4386 | 1663399200645 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window {"handle": "49363c9b-26f2-46e1-a7d8-2740b87724ee"}
[task 2022-09-17T07:20:00.653Z] 07:20:00 INFO - PID 4386 | 1663399200652 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.654Z] 07:20:00 INFO - PID 4386 | 1663399200653 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/alert/dismiss {}
[task 2022-09-17T07:20:00.662Z] 07:20:00 INFO - PID 4386 | 1663399200660 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.jsm:12:1\nWebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:192:5\nNoSuchAlertError@chrome://remote/content/shared/webdriver/Errors.jsm:393:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://remote/content/marionette/driver.js:2672:11\nGeckoDriver.prototype.dismissDialog@chrome://remote/content/marionette/driver.js:2579:8\ndespatch@chrome://remote/content/marionette/server.js:304:40\nexecute@chrome://remote/content/marionette/server.js:277:16\nonPacket/<@chrome://remote/content/marionette/server.js:250:20\nonPacket@chrome://remote/content/marionette/server.js:251:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:502:20\n"}}
[task 2022-09-17T07:20:00.662Z] 07:20:00 INFO - PID 4386 | 1663399200661 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window {"handle": "6e53da39-237b-424a-b290-a77418e9850d"}
[task 2022-09-17T07:20:00.687Z] 07:20:00 INFO - PID 4386 | 1663399200686 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.688Z] 07:20:00 INFO - PID 4386 | 1663399200687 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/alert/dismiss {}
[task 2022-09-17T07:20:00.697Z] 07:20:00 INFO - PID 4386 | 1663399200695 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.jsm:12:1\nWebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:192:5\nNoSuchAlertError@chrome://remote/content/shared/webdriver/Errors.jsm:393:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://remote/content/marionette/driver.js:2672:11\nGeckoDriver.prototype.dismissDialog@chrome://remote/content/marionette/driver.js:2579:8\ndespatch@chrome://remote/content/marionette/server.js:304:40\nexecute@chrome://remote/content/marionette/server.js:277:16\nonPacket/<@chrome://remote/content/marionette/server.js:250:20\nonPacket@chrome://remote/content/marionette/server.js:251:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:502:20\n"}}
[task 2022-09-17T07:20:00.698Z] 07:20:00 INFO - PID 4386 | 1663399200696 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window {"handle": "49363c9b-26f2-46e1-a7d8-2740b87724ee"}
[task 2022-09-17T07:20:00.725Z] 07:20:00 INFO - PID 4386 | 1663399200724 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.726Z] 07:20:00 INFO - PID 4386 | 1663399200725 webdriver::server DEBUG -> GET /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window
[task 2022-09-17T07:20:00.733Z] 07:20:00 INFO - PID 4386 | 1663399200732 webdriver::server DEBUG <- 200 OK {"value":"49363c9b-26f2-46e1-a7d8-2740b87724ee"}
[task 2022-09-17T07:20:00.734Z] 07:20:00 INFO - PID 4386 | 1663399200733 webdriver::server DEBUG -> GET /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window/handles
[task 2022-09-17T07:20:00.741Z] 07:20:00 INFO - PID 4386 | 1663399200740 webdriver::server DEBUG <- 200 OK {"value":["6e53da39-237b-424a-b290-a77418e9850d","49363c9b-26f2-46e1-a7d8-2740b87724ee"]}
[task 2022-09-17T07:20:00.742Z] 07:20:00 INFO - PID 4386 | 1663399200741 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window {"handle": "6e53da39-237b-424a-b290-a77418e9850d"}
[task 2022-09-17T07:20:00.765Z] 07:20:00 INFO - PID 4386 | 1663399200764 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.766Z] 07:20:00 INFO - PID 4386 | 1663399200765 webdriver::server DEBUG -> GET /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window/handles
[task 2022-09-17T07:20:00.776Z] 07:20:00 INFO - PID 4386 | 1663399200775 webdriver::server DEBUG <- 200 OK {"value":["6e53da39-237b-424a-b290-a77418e9850d","49363c9b-26f2-46e1-a7d8-2740b87724ee"]}
[task 2022-09-17T07:20:00.777Z] 07:20:00 INFO - PID 4386 | 1663399200776 webdriver::server DEBUG -> DELETE /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window
[task 2022-09-17T07:20:00.944Z] 07:20:00 INFO - PID 4386 | 1663399200943 webdriver::server DEBUG <- 200 OK {"value":["49363c9b-26f2-46e1-a7d8-2740b87724ee"]}
[task 2022-09-17T07:20:00.945Z] 07:20:00 INFO - PID 4386 | 1663399200944 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/window {"handle": "49363c9b-26f2-46e1-a7d8-2740b87724ee"}
[task 2022-09-17T07:20:00.973Z] 07:20:00 INFO - PID 4386 | 1663399200972 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:00.974Z] 07:20:00 INFO - PID 4386 | 1663399200973 webdriver::server DEBUG -> POST /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23/frame {"id": null}
[task 2022-09-17T07:20:01.009Z] 07:20:01 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/permissions/set.py | expected OK
[task 2022-09-17T07:20:01.009Z] 07:20:01 INFO - TEST-INFO took 80003ms
[task 2022-09-17T07:20:01.009Z] 07:20:01 INFO - Restarting browser for new test group
[task 2022-09-17T07:20:01.013Z] 07:20:01 INFO - PID 4386 | 1663399201011 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-09-17T07:20:01.015Z] 07:20:01 INFO - PID 4386 | 1663399201014 webdriver::server DEBUG -> DELETE /session/ac4c92bf-31bc-45ee-bbbc-2b059cec9c23
[task 2022-09-17T07:20:01.101Z] 07:20:01 INFO - Closing logging queue
[task 2022-09-17T07:20:01.102Z] 07:20:01 INFO - queue closed
[task 2022-09-17T07:20:01.189Z] 07:20:01 INFO - PID 4479 | 1663399201187 geckodriver INFO Listening on 127.0.0.1:44803
[task 2022-09-17T07:20:01.189Z] 07:20:01 INFO - Starting runner
Should we file bugs for each one individually?
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 4•2 years ago
|
||
All the failures are actually bug 1791111.
Status: NEW → RESOLVED
Closed: 2 years ago
Flags: needinfo?(hskupin)
Resolution: --- → DUPLICATE
|
||
Comment 5•1 year ago
|
||
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.
Description
•