Closed Bug 1665881 Opened 4 years ago Closed 4 years ago

Intermittent /webdriver/tests/is_element_enabled/enabled.py | test_no_browsing_context - AssertionError: assert 'no such element' == 'no such window'

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1665250

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure)

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

[task 2020-09-18T08:12:19.004Z] 08:12:19 INFO - STDOUT: PASSED
[task 2020-09-18T08:12:19.004Z] 08:12:19 INFO - PID 32099 | 1600416739000 webdriver::server DEBUG <- 200 OK {"value":false}
[task 2020-09-18T08:12:19.008Z] 08:12:19 INFO - PID 32099 | 1600416739001 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/timeouts {"implicit": 0}
[task 2020-09-18T08:12:19.009Z] 08:12:19 INFO - PID 32099 | 1600416739001 Marionette DEBUG 0 -> [0,688,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-09-18T08:12:19.009Z] 08:12:19 INFO - PID 32099 | 1600416739001 Marionette DEBUG 0 <- [1,688,null,{"value":null}]
[task 2020-09-18T08:12:19.010Z] 08:12:19 INFO - PID 32099 | 1600416739002 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T08:12:19.010Z] 08:12:19 INFO - PID 32099 | 1600416739002 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/timeouts {"pageLoad": 300000}
[task 2020-09-18T08:12:19.010Z] 08:12:19 INFO - PID 32099 | 1600416739003 Marionette DEBUG 0 -> [0,689,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-09-18T08:12:19.012Z] 08:12:19 INFO - PID 32099 | 1600416739003 Marionette DEBUG 0 <- [1,689,null,{"value":null}]
[task 2020-09-18T08:12:19.012Z] 08:12:19 INFO - PID 32099 | 1600416739003 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T08:12:19.012Z] 08:12:19 INFO - PID 32099 | 1600416739005 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/timeouts {"script": 30000}
[task 2020-09-18T08:12:19.013Z] 08:12:19 INFO - PID 32099 | 1600416739005 Marionette DEBUG 0 -> [0,690,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-09-18T08:12:19.014Z] 08:12:19 INFO - PID 32099 | 1600416739005 Marionette DEBUG 0 <- [1,690,null,{"value":null}]
[task 2020-09-18T08:12:19.014Z] 08:12:19 INFO - PID 32099 | 1600416739005 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T08:12:19.015Z] 08:12:19 INFO - PID 32099 | 1600416739007 webdriver::server DEBUG -> GET /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window
[task 2020-09-18T08:12:19.016Z] 08:12:19 INFO - PID 32099 | 1600416739007 Marionette DEBUG 0 -> [0,691,"WebDriver:GetWindowHandle",{}]
[task 2020-09-18T08:12:19.016Z] 08:12:19 INFO - PID 32099 | 1600416739007 Marionette DEBUG 0 <- [1,691,null,{"value":"17"}]
[task 2020-09-18T08:12:19.017Z] 08:12:19 INFO - PID 32099 | 1600416739007 webdriver::server DEBUG <- 200 OK {"value":"17"}
[task 2020-09-18T08:12:19.017Z] 08:12:19 INFO - PID 32099 | 1600416739008 webdriver::server DEBUG -> GET /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window
[task 2020-09-18T08:12:19.018Z] 08:12:19 INFO - PID 32099 | 1600416739011 Marionette DEBUG 0 -> [0,692,"WebDriver:GetWindowHandle",{}]
[task 2020-09-18T08:12:19.019Z] 08:12:19 INFO - PID 32099 | 1600416739011 Marionette DEBUG 0 <- [1,692,null,{"value":"17"}]
[task 2020-09-18T08:12:19.019Z] 08:12:19 INFO - PID 32099 | 1600416739011 webdriver::server DEBUG <- 200 OK {"value":"17"}
[task 2020-09-18T08:12:19.019Z] 08:12:19 INFO - PID 32099 | 1600416739011 webdriver::server DEBUG -> GET /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window/handles
[task 2020-09-18T08:12:19.021Z] 08:12:19 INFO - PID 32099 | 1600416739013 Marionette DEBUG 0 -> [0,693,"WebDriver:GetWindowHandles",{}]
[task 2020-09-18T08:12:19.021Z] 08:12:19 INFO - PID 32099 | 1600416739013 Marionette DEBUG 0 <- [1,693,null,["17"]]
[task 2020-09-18T08:12:19.021Z] 08:12:19 INFO - PID 32099 | 1600416739013 webdriver::server DEBUG <- 200 OK {"value":["17"]}
[task 2020-09-18T08:12:19.022Z] 08:12:19 INFO - PID 32099 | 1600416739014 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window {"handle": "17"}
[task 2020-09-18T08:12:19.024Z] 08:12:19 INFO - PID 32099 | 1600416739014 Marionette DEBUG 0 -> [0,694,"WebDriver:SwitchToWindow",{"handle":"17","name":"17"}]
[task 2020-09-18T08:12:19.024Z] 08:12:19 INFO - PID 32099 | 1600416739014 Marionette DEBUG 0 <- [1,694,null,{"value":null}]
[task 2020-09-18T08:12:19.024Z] 08:12:19 INFO - PID 32099 | 1600416739015 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T08:12:19.024Z] 08:12:19 INFO - PID 32099 | 1600416739016 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/alert/dismiss {}
[task 2020-09-18T08:12:19.026Z] 08:12:19 INFO - PID 32099 | 1600416739016 Marionette DEBUG 0 -> [0,695,"WebDriver:DismissAlert",{}]
[task 2020-09-18T08:12:19.026Z] 08:12:19 INFO - PID 32099 | 1600416739017 Marionette DEBUG 0 <- [1,695,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nNoSuchA ... t@chrome://marionette/content/server.js:241:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20\n"},null]
[task 2020-09-18T08:12:19.027Z] 08:12:19 INFO - PID 32099 | 1600416739017 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nNoSuchAlertError@chrome://marionette/content/error.js:382:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3398:11\nGeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3301:8\ndespatch@chrome://marionette/content/server.js:297:40\nexecute@chrome://marionette/content/server.js:267:16\nonPacket/<@chrome://marionette/content/server.js:240:20\nonPacket@chrome://marionette/content/server.js:241:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20\n"}}
[task 2020-09-18T08:12:19.028Z] 08:12:19 INFO - PID 32099 | 1600416739018 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window {"handle": "17"}
[task 2020-09-18T08:12:19.028Z] 08:12:19 INFO - PID 32099 | 1600416739018 Marionette DEBUG 0 -> [0,696,"WebDriver:SwitchToWindow",{"handle":"17","name":"17"}]
[task 2020-09-18T08:12:19.030Z] 08:12:19 INFO - PID 32099 | 1600416739019 Marionette DEBUG 0 <- [1,696,null,{"value":null}]
[task 2020-09-18T08:12:19.030Z] 08:12:19 INFO - PID 32099 | 1600416739019 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T08:12:19.030Z] 08:12:19 INFO - PID 32099 | 1600416739020 webdriver::server DEBUG -> GET /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window
[task 2020-09-18T08:12:19.030Z] 08:12:19 INFO - PID 32099 | 1600416739020 Marionette DEBUG 0 -> [0,697,"WebDriver:GetWindowHandle",{}]
[task 2020-09-18T08:12:19.032Z] 08:12:19 INFO - PID 32099 | 1600416739020 Marionette DEBUG 0 <- [1,697,null,{"value":"17"}]
[task 2020-09-18T08:12:19.032Z] 08:12:19 INFO - PID 32099 | 1600416739020 webdriver::server DEBUG <- 200 OK {"value":"17"}
[task 2020-09-18T08:12:19.032Z] 08:12:19 INFO - PID 32099 | 1600416739021 webdriver::server DEBUG -> GET /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window/handles
[task 2020-09-18T08:12:19.032Z] 08:12:19 INFO - PID 32099 | 1600416739021 Marionette DEBUG 0 -> [0,698,"WebDriver:GetWindowHandles",{}]
[task 2020-09-18T08:12:19.034Z] 08:12:19 INFO - PID 32099 | 1600416739022 Marionette DEBUG 0 <- [1,698,null,["17"]]
[task 2020-09-18T08:12:19.035Z] 08:12:19 INFO - PID 32099 | 1600416739022 webdriver::server DEBUG <- 200 OK {"value":["17"]}
[task 2020-09-18T08:12:19.035Z] 08:12:19 INFO - PID 32099 | 1600416739023 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window {"handle": "17"}
[task 2020-09-18T08:12:19.035Z] 08:12:19 INFO - PID 32099 | 1600416739024 Marionette DEBUG 0 -> [0,699,"WebDriver:SwitchToWindow",{"handle":"17","name":"17"}]
[task 2020-09-18T08:12:19.036Z] 08:12:19 INFO - PID 32099 | 1600416739024 Marionette DEBUG 0 <- [1,699,null,{"value":null}]
[task 2020-09-18T08:12:19.037Z] 08:12:19 INFO - PID 32099 | 1600416739024 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T08:12:19.037Z] 08:12:19 INFO - PID 32099 | 1600416739025 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/window/rect {"width": 800, "height": 600}
[task 2020-09-18T08:12:19.039Z] 08:12:19 INFO - PID 32099 | 1600416739025 Marionette DEBUG 0 -> [0,700,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-09-18T08:12:19.039Z] 08:12:19 INFO - PID 32099 | 1600416739027 Marionette DEBUG 0 <- [1,700,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-09-18T08:12:19.039Z] 08:12:19 INFO - PID 32099 | 1600416739027 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-09-18T08:12:19.040Z] 08:12:19 INFO - PID 32099 | 1600416739029 webdriver::server DEBUG -> POST /session/947b0124-923f-4765-ba9a-5a9b57ceeac6/frame {"id": null}
[task 2020-09-18T08:12:19.041Z] 08:12:19 INFO - PID 32099 | 1600416739029 Marionette DEBUG 0 -> [0,701,"WebDriver:SwitchToFrame",{"id":null}]
[task 2020-09-18T08:12:19.041Z] 08:12:19 INFO - PID 32099 | 1600416739030 Marionette DEBUG 0 <- [1,701,null,{"value":null}]
[task 2020-09-18T08:12:19.041Z] 08:12:19 INFO - PID 32099 | 1600416739030 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T08:12:19.042Z] 08:12:19 INFO - STDOUT: =================================== FAILURES ===================================
[task 2020-09-18T08:12:19.042Z] 08:12:19 INFO - STDOUT: ___________________________ test_no_browsing_context ___________________________
[task 2020-09-18T08:12:19.043Z] 08:12:19 INFO - STDOUT: session = <Session 947b0124-923f-4765-ba9a-5a9b57ceeac6>, closed_frame = None
[task 2020-09-18T08:12:19.043Z] 08:12:19 INFO - STDOUT: def test_no_browsing_context(session, closed_frame):
[task 2020-09-18T08:12:19.043Z] 08:12:19 INFO - STDOUT: response = is_element_enabled(session, "foo")
[task 2020-09-18T08:12:19.044Z] 08:12:19 INFO - STDOUT: > assert_error(response, "no such window")
[task 2020-09-18T08:12:19.044Z] 08:12:19 INFO - STDOUT: closed_frame = None
[task 2020-09-18T08:12:19.044Z] 08:12:19 INFO - STDOUT: response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-18T08:12:19.044Z] 08:12:19 INFO - STDOUT: session = <Session 947b0124-923f-4765-ba9a-5a9b57ceeac6>
[task 2020-09-18T08:12:19.046Z] 08:12:19 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/is_element_enabled/enabled.py
[task 2020-09-18T08:12:19.046Z] 08:12:19 INFO - STDOUT: :24:
[task 2020-09-18T08:12:19.046Z] 08:12:19 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-09-18T08:12:19.047Z] 08:12:19 INFO - STDOUT: response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-18T08:12:19.047Z] 08:12:19 INFO - STDOUT: error_code = 'no such window'
[task 2020-09-18T08:12:19.047Z] 08:12:19 INFO - STDOUT: def assert_error(response, error_code):
[task 2020-09-18T08:12:19.048Z] 08:12:19 INFO - STDOUT: """
[task 2020-09-18T08:12:19.049Z] 08:12:19 INFO - STDOUT: Verify that the provided webdriver.Response instance described
[task 2020-09-18T08:12:19.049Z] 08:12:19 INFO - STDOUT: a valid error response as defined by dfn-send-an-error and
[task 2020-09-18T08:12:19.049Z] 08:12:19 INFO - STDOUT: the provided error code.
[task 2020-09-18T08:12:19.049Z] 08:12:19 INFO - STDOUT:
[task 2020-09-18T08:12:19.050Z] 08:12:19 INFO - STDOUT: :param response: webdriver.Response instance.
[task 2020-09-18T08:12:19.051Z] 08:12:19 INFO - STDOUT: :param error_code: String value of the expected error code
[task 2020-09-18T08:12:19.053Z] 08:12:19 INFO - STDOUT: """
[task 2020-09-18T08:12:19.054Z] 08:12:19 INFO - STDOUT: assert response.status == errors[error_code]
[task 2020-09-18T08:12:19.054Z] 08:12:19 INFO - STDOUT: assert "value" in response.body
[task 2020-09-18T08:12:19.054Z] 08:12:19 INFO - STDOUT: > assert response.body["value"]["error"] == error_code
[task 2020-09-18T08:12:19.056Z] 08:12:19 INFO - STDOUT: E AssertionError: assert 'no such element' == 'no such window'
[task 2020-09-18T08:12:19.056Z] 08:12:19 INFO - STDOUT: E - no such element
[task 2020-09-18T08:12:19.056Z] 08:12:19 INFO - STDOUT: E + no such window
[task 2020-09-18T08:12:19.057Z] 08:12:19 INFO - STDOUT: error_code = 'no such window'
[task 2020-09-18T08:12:19.057Z] 08:12:19 INFO - STDOUT: response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-18T08:12:19.057Z] 08:12:19 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/asserts.py
[task 2020-09-18T08:12:19.058Z] 08:12:19 INFO - STDOUT: :54: AssertionError
[task 2020-09-18T08:12:19.059Z] 08:12:19 INFO - STDOUT: ===================== 1 failed, 35 passed in 5.99 seconds ======================
[task 2020-09-18T08:12:19.061Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.061Z] 08:12:19 INFO - TEST-PASS | /webdriver/tests/is_element_enabled/enabled.py | test_no_top_browsing_context
[task 2020-09-18T08:12:19.061Z] 08:12:19 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/is_element_enabled/enabled.py | test_no_browsing_context - AssertionError: assert 'no such element' == 'no such window'
[task 2020-09-18T08:12:19.061Z] 08:12:19 INFO - session = <Session 947b0124-923f-4765-ba9a-5a9b57ceeac6>, closed_frame = None
[task 2020-09-18T08:12:19.061Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.061Z] 08:12:19 INFO - def test_no_browsing_context(session, closed_frame):
[task 2020-09-18T08:12:19.061Z] 08:12:19 INFO - response = is_element_enabled(session, "foo")
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO - > assert_error(response, "no such window")
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO - closed_frame = None
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO - response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO - session = <Session 947b0124-923f-4765-ba9a-5a9b57ceeac6>
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO - tests/web-platform/tests/webdriver/tests/is_element_enabled/enabled.py:24:
[task 2020-09-18T08:12:19.062Z] 08:12:19 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - error_code = 'no such window'
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - def assert_error(response, error_code):
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - """
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - Verify that the provided webdriver.Response instance described
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - a valid error response as defined by dfn-send-an-error and
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - the provided error code.
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.063Z] 08:12:19 INFO - :param response: webdriver.Response instance.
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - :param error_code: String value of the expected error code
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - """
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - assert response.status == errors[error_code]
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - assert "value" in response.body
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - > assert response.body["value"]["error"] == error_code
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - E AssertionError: assert 'no such element' == 'no such window'
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - E - no such element
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - E + no such window
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - error_code = 'no such window'
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO -
[task 2020-09-18T08:12:19.064Z] 08:12:19 INFO - tests/web-platform/tests/webdriver/tests/support/asserts.py:54: AssertionError
[task 2020-09-18T08:12:19.070Z] 08:12:19 INFO - ..................................
[task 2020-09-18T08:12:19.070Z] 08:12:19 INFO - TEST-OK | /webdriver/tests/is_element_enabled/enabled.py | took 6185ms
[task 2020-09-18T08:12:19.127Z] 08:12:19 INFO - Closing logging queue
[task 2020-09-18T08:12:19.127Z] 08:12:19 INFO - queue closed
[task 2020-09-18T08:12:19.127Z] 08:12:19 INFO - Starting runner
[task 2020-09-18T08:12:19.145Z] 08:12:19 INFO - PID 32561 | 1600416739141 geckodriver INFO Listening on 127.0.0.1:44393
[task 2020-09-18T08:12:19.146Z] 08:12:19 INFO - WebDriver HTTP server listening at http://127.0.0.1:44393/
[task 2020-09-18T08:12:19.146Z] 08:12:19 INFO - TEST-START | /webdriver/tests/is_element_enabled/user_prompts.py

Status: NEW → RESOLVED
Closed: 4 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.