Closed Bug 1665880 Opened 4 years ago Closed 4 years ago

Intermittent /webdriver/tests/delete_cookie/delete.py | test_no_browsing_context - assert 200 == 404

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1665549

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2020-09-18T07:33:36.787Z] 07:33:36 INFO - STDOUT: PASSED
[task 2020-09-18T07:33:36.788Z] 07:33:36 INFO - PID 4772 | 1600414416782 Marionette DEBUG 0 <- [1,74,null,{"value":null}]
[task 2020-09-18T07:33:36.788Z] 07:33:36 INFO - PID 4772 | 1600414416784 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.795Z] 07:33:36 INFO - PID 4772 | 1600414416787 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/timeouts {"implicit": 0}
[task 2020-09-18T07:33:36.796Z] 07:33:36 INFO - PID 4772 | 1600414416787 Marionette DEBUG 0 -> [0,75,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-09-18T07:33:36.796Z] 07:33:36 INFO - PID 4772 | 1600414416788 Marionette DEBUG 0 <- [1,75,null,{"value":null}]
[task 2020-09-18T07:33:36.797Z] 07:33:36 INFO - PID 4772 | 1600414416789 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.797Z] 07:33:36 INFO - PID 4772 | 1600414416792 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/timeouts {"pageLoad": 300000}
[task 2020-09-18T07:33:36.798Z] 07:33:36 INFO - PID 4772 | 1600414416792 Marionette DEBUG 0 -> [0,76,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-09-18T07:33:36.799Z] 07:33:36 INFO - PID 4772 | 1600414416793 Marionette DEBUG 0 <- [1,76,null,{"value":null}]
[task 2020-09-18T07:33:36.799Z] 07:33:36 INFO - PID 4772 | 1600414416794 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.800Z] 07:33:36 INFO - PID 4772 | 1600414416795 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/timeouts {"script": 30000}
[task 2020-09-18T07:33:36.800Z] 07:33:36 INFO - PID 4772 | 1600414416795 Marionette DEBUG 0 -> [0,77,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-09-18T07:33:36.801Z] 07:33:36 INFO - PID 4772 | 1600414416796 Marionette DEBUG 0 <- [1,77,null,{"value":null}]
[task 2020-09-18T07:33:36.801Z] 07:33:36 INFO - PID 4772 | 1600414416797 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.808Z] 07:33:36 INFO - PID 4772 | 1600414416800 webdriver::server DEBUG -> GET /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window
[task 2020-09-18T07:33:36.808Z] 07:33:36 INFO - PID 4772 | 1600414416800 Marionette DEBUG 0 -> [0,78,"WebDriver:GetWindowHandle",{}]
[task 2020-09-18T07:33:36.808Z] 07:33:36 INFO - PID 4772 | 1600414416801 Marionette DEBUG 0 <- [1,78,null,{"value":"20"}]
[task 2020-09-18T07:33:36.809Z] 07:33:36 INFO - PID 4772 | 1600414416802 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2020-09-18T07:33:36.809Z] 07:33:36 INFO - PID 4772 | 1600414416804 webdriver::server DEBUG -> GET /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window
[task 2020-09-18T07:33:36.809Z] 07:33:36 INFO - PID 4772 | 1600414416804 Marionette DEBUG 0 -> [0,79,"WebDriver:GetWindowHandle",{}]
[task 2020-09-18T07:33:36.810Z] 07:33:36 INFO - PID 4772 | 1600414416804 Marionette DEBUG 0 <- [1,79,null,{"value":"20"}]
[task 2020-09-18T07:33:36.810Z] 07:33:36 INFO - PID 4772 | 1600414416805 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2020-09-18T07:33:36.813Z] 07:33:36 INFO - PID 4772 | 1600414416808 webdriver::server DEBUG -> GET /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window/handles
[task 2020-09-18T07:33:36.814Z] 07:33:36 INFO - PID 4772 | 1600414416809 Marionette DEBUG 0 -> [0,80,"WebDriver:GetWindowHandles",{}]
[task 2020-09-18T07:33:36.814Z] 07:33:36 INFO - PID 4772 | 1600414416809 Marionette DEBUG 0 <- [1,80,null,["20"]]
[task 2020-09-18T07:33:36.814Z] 07:33:36 INFO - PID 4772 | 1600414416810 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2020-09-18T07:33:36.817Z] 07:33:36 INFO - PID 4772 | 1600414416813 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window {"handle": "20"}
[task 2020-09-18T07:33:36.818Z] 07:33:36 INFO - PID 4772 | 1600414416814 Marionette DEBUG 0 -> [0,81,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2020-09-18T07:33:36.818Z] 07:33:36 INFO - PID 4772 | 1600414416815 Marionette DEBUG 0 <- [1,81,null,{"value":null}]
[task 2020-09-18T07:33:36.818Z] 07:33:36 INFO - PID 4772 | 1600414416816 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.825Z] 07:33:36 INFO - PID 4772 | 1600414416818 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/alert/dismiss {}
[task 2020-09-18T07:33:36.825Z] 07:33:36 INFO - PID 4772 | 1600414416818 Marionette DEBUG 0 -> [0,82,"WebDriver:DismissAlert",{}]
[task 2020-09-18T07:33:36.825Z] 07:33:36 INFO - PID 4772 | 1600414416819 Marionette DEBUG 0 <- [1,82,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nNoSuchAl ... t@chrome://marionette/content/server.js:241:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20\n"},null]
[task 2020-09-18T07:33:36.826Z] 07:33:36 INFO - PID 4772 | 1600414416821 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-18T07:33:36.829Z] 07:33:36 INFO - PID 4772 | 1600414416823 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window {"handle": "20"}
[task 2020-09-18T07:33:36.829Z] 07:33:36 INFO - PID 4772 | 1600414416824 Marionette DEBUG 0 -> [0,83,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2020-09-18T07:33:36.832Z] 07:33:36 INFO - PID 4772 | 1600414416825 Marionette DEBUG 0 <- [1,83,null,{"value":null}]
[task 2020-09-18T07:33:36.832Z] 07:33:36 INFO - PID 4772 | 1600414416826 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.832Z] 07:33:36 INFO - PID 4772 | 1600414416827 webdriver::server DEBUG -> GET /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window
[task 2020-09-18T07:33:36.835Z] 07:33:36 INFO - PID 4772 | 1600414416828 Marionette DEBUG 0 -> [0,84,"WebDriver:GetWindowHandle",{}]
[task 2020-09-18T07:33:36.835Z] 07:33:36 INFO - PID 4772 | 1600414416828 Marionette DEBUG 0 <- [1,84,null,{"value":"20"}]
[task 2020-09-18T07:33:36.835Z] 07:33:36 INFO - PID 4772 | 1600414416830 webdriver::server DEBUG <- 200 OK {"value":"20"}
[task 2020-09-18T07:33:36.835Z] 07:33:36 INFO - PID 4772 | 1600414416831 webdriver::server DEBUG -> GET /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window/handles
[task 2020-09-18T07:33:36.836Z] 07:33:36 INFO - PID 4772 | 1600414416831 Marionette DEBUG 0 -> [0,85,"WebDriver:GetWindowHandles",{}]
[task 2020-09-18T07:33:36.836Z] 07:33:36 INFO - PID 4772 | 1600414416832 Marionette DEBUG 0 <- [1,85,null,["20"]]
[task 2020-09-18T07:33:36.836Z] 07:33:36 INFO - PID 4772 | 1600414416833 webdriver::server DEBUG <- 200 OK {"value":["20"]}
[task 2020-09-18T07:33:36.843Z] 07:33:36 INFO - PID 4772 | 1600414416835 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window {"handle": "20"}
[task 2020-09-18T07:33:36.844Z] 07:33:36 INFO - PID 4772 | 1600414416836 Marionette DEBUG 0 -> [0,86,"WebDriver:SwitchToWindow",{"handle":"20","name":"20"}]
[task 2020-09-18T07:33:36.844Z] 07:33:36 INFO - PID 4772 | 1600414416837 Marionette DEBUG 0 <- [1,86,null,{"value":null}]
[task 2020-09-18T07:33:36.845Z] 07:33:36 INFO - PID 4772 | 1600414416838 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.846Z] 07:33:36 INFO - PID 4772 | 1600414416839 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/window/rect {"width": 800, "height": 600}
[task 2020-09-18T07:33:36.846Z] 07:33:36 INFO - PID 4772 | 1600414416840 Marionette DEBUG 0 -> [0,87,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-09-18T07:33:36.846Z] 07:33:36 INFO - PID 4772 | 1600414416841 Marionette DEBUG 0 <- [1,87,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-09-18T07:33:36.846Z] 07:33:36 INFO - PID 4772 | 1600414416842 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-09-18T07:33:36.853Z] 07:33:36 INFO - PID 4772 | 1600414416844 webdriver::server DEBUG -> POST /session/2bf2812d-e731-43a5-b73e-2d895bf99bfe/frame {"id": null}
[task 2020-09-18T07:33:36.854Z] 07:33:36 INFO - PID 4772 | 1600414416845 Marionette DEBUG 0 -> [0,88,"WebDriver:SwitchToFrame",{"id":null}]
[task 2020-09-18T07:33:36.854Z] 07:33:36 INFO - PID 4772 | 1600414416847 Marionette DEBUG 0 <- [1,88,null,{"value":null}]
[task 2020-09-18T07:33:36.855Z] 07:33:36 INFO - PID 4772 | 1600414416848 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-09-18T07:33:36.855Z] 07:33:36 INFO - STDOUT: ================================== FAILURES ===================================
[task 2020-09-18T07:33:36.855Z] 07:33:36 INFO - STDOUT: __________________________ test_no_browsing_context ___________________________
[task 2020-09-18T07:33:36.856Z] 07:33:36 INFO - STDOUT: session = <Session 2bf2812d-e731-43a5-b73e-2d895bf99bfe>, closed_frame = None
[task 2020-09-18T07:33:36.856Z] 07:33:36 INFO - STDOUT: def test_no_browsing_context(session, closed_frame):
[task 2020-09-18T07:33:36.857Z] 07:33:36 INFO - STDOUT: response = delete_cookie(session, "foo")
[task 2020-09-18T07:33:36.858Z] 07:33:36 INFO - STDOUT: > assert_error(response, "no such window")
[task 2020-09-18T07:33:36.858Z] 07:33:36 INFO - STDOUT: closed_frame = None
[task 2020-09-18T07:33:36.859Z] 07:33:36 INFO - STDOUT: response = <Responsetatus=200 body={"value": null}>
[task 2020-09-18T07:33:36.860Z] 07:33:36 INFO - STDOUT: session = <Session 2bf2812d-e731-43a5-b73e-2d895bf99bfe>
[task 2020-09-18T07:33:36.860Z] 07:33:36 INFO - STDOUT: tests\web-platform\tests\webdriver\tests\delete_cookie\delete.py
[task 2020-09-18T07:33:36.861Z] 07:33:36 INFO - STDOUT: :24:
[task 2020-09-18T07:33:36.861Z] 07:33:36 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-09-18T07:33:36.862Z] 07:33:36 INFO - STDOUT: response = <Responsetatus=200 body={"value": null}>
[task 2020-09-18T07:33:36.862Z] 07:33:36 INFO - STDOUT: error_code = 'no such window'
[task 2020-09-18T07:33:36.863Z] 07:33:36 INFO - STDOUT: def assert_error(response, error_code):
[task 2020-09-18T07:33:36.863Z] 07:33:36 INFO - STDOUT: """
[task 2020-09-18T07:33:36.863Z] 07:33:36 INFO - STDOUT: Verify that the provided webdriver.Response instance described
[task 2020-09-18T07:33:36.864Z] 07:33:36 INFO - STDOUT: a valid error response as defined by dfn-send-an-error and
[task 2020-09-18T07:33:36.864Z] 07:33:36 INFO - STDOUT: the provided error code.
[task 2020-09-18T07:33:36.867Z] 07:33:36 INFO - STDOUT:
[task 2020-09-18T07:33:36.867Z] 07:33:36 INFO - STDOUT: :param response: webdriver.Response instance.
[task 2020-09-18T07:33:36.867Z] 07:33:36 INFO - STDOUT: :param error_code: String value of the expected error code
[task 2020-09-18T07:33:36.868Z] 07:33:36 INFO - STDOUT: """
[task 2020-09-18T07:33:36.868Z] 07:33:36 INFO - STDOUT: > assert response.status == errors[error_code]
[task 2020-09-18T07:33:36.868Z] 07:33:36 INFO - STDOUT: E assert 200 == 404
[task 2020-09-18T07:33:36.868Z] 07:33:36 INFO - STDOUT: E + where 200 = <Responsetatus=200 body={"value": null}>.status
[task 2020-09-18T07:33:36.868Z] 07:33:36 INFO - STDOUT: error_code = 'no such window'
[task 2020-09-18T07:33:36.871Z] 07:33:36 INFO - STDOUT: response = <Responsetatus=200 body={"value": null}>
[task 2020-09-18T07:33:36.871Z] 07:33:36 INFO - STDOUT: tests\web-platform\tests\webdriver\tests\support\asserts.py
[task 2020-09-18T07:33:36.871Z] 07:33:36 INFO - STDOUT: :52: AssertionError
[task 2020-09-18T07:33:36.871Z] 07:33:36 INFO - STDOUT: ===================== 1 failed, 3 passed in 7.50 seconds ======================
[task 2020-09-18T07:33:36.888Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.888Z] 07:33:36 INFO - TEST-PASS | /webdriver/tests/delete_cookie/delete.py | test_null_response_value
[task 2020-09-18T07:33:36.888Z] 07:33:36 INFO - TEST-PASS | /webdriver/tests/delete_cookie/delete.py | test_no_top_browsing_context
[task 2020-09-18T07:33:36.889Z] 07:33:36 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/delete_cookie/delete.py | test_no_browsing_context - assert 200 == 404
[task 2020-09-18T07:33:36.889Z] 07:33:36 INFO - session = <Session 2bf2812d-e731-43a5-b73e-2d895bf99bfe>, closed_frame = None
[task 2020-09-18T07:33:36.889Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.889Z] 07:33:36 INFO - def test_no_browsing_context(session, closed_frame):
[task 2020-09-18T07:33:36.889Z] 07:33:36 INFO - response = delete_cookie(session, "foo")
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO - > assert_error(response, "no such window")
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO - closed_frame = None
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO - response = <Responsetatus=200 body={"value": null}>
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO - session = <Session 2bf2812d-e731-43a5-b73e-2d895bf99bfe>
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO - tests\web-platform\tests\webdriver\tests\delete_cookie\delete.py:24:
[task 2020-09-18T07:33:36.890Z] 07:33:36 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - response = <Responsetatus=200 body={"value": null}>
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - error_code = 'no such window'
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - def assert_error(response, error_code):
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - """
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - Verify that the provided webdriver.Response instance described
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - a valid error response as defined by dfn-send-an-error and
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - the provided error code.
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - :param response: webdriver.Response instance.
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - :param error_code: String value of the expected error code
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - """
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - > assert response.status == errors[error_code]
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - E assert 200 == 404
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - E + where 200 = <Responsetatus=200 body={"value": null}>.status
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - error_code = 'no such window'
[task 2020-09-18T07:33:36.891Z] 07:33:36 INFO - response = <Responsetatus=200 body={"value": null}>
[task 2020-09-18T07:33:36.892Z] 07:33:36 INFO -
[task 2020-09-18T07:33:36.892Z] 07:33:36 INFO - tests\web-platform\tests\webdriver\tests\support\asserts.py:52: AssertionError
[task 2020-09-18T07:33:36.892Z] 07:33:36 INFO - .
[task 2020-09-18T07:33:36.892Z] 07:33:36 INFO - TEST-OK | /webdriver/tests/delete_cookie/delete.py | took 7893ms
[task 2020-09-18T07:33:36.969Z] 07:33:36 INFO - PID 4772 | Exiting due to channel error.
[task 2020-09-18T07:33:36.969Z] 07:33:36 INFO - PID 4772 | Exiting due to channel error.
[task 2020-09-18T07:33:36.969Z] 07:33:36 INFO - PID 4772 | Exiting due to channel error.
[task 2020-09-18T07:33:36.969Z] 07:33:36 INFO - Closing logging queue
[task 2020-09-18T07:33:36.970Z] 07:33:36 INFO - queue closed
[task 2020-09-18T07:33:36.970Z] 07:33:36 INFO - Starting runner
[task 2020-09-18T07:33:37.526Z] 07:33:37 INFO - PID 6124 | 1600414417519 geckodriver INFO Listening on 127.0.0.1:62564
[task 2020-09-18T07:33:38.041Z] 07:33:38 INFO - WebDriver HTTP server listening at http://127.0.0.1:62564/
[task 2020-09-18T07:33:38.041Z] 07:33:38 INFO - TEST-START | /webdriver/tests/delete_cookie/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.