Closed
Bug 1768649
Opened 2 years ago
Closed 2 years ago
Intermittent [tier2] TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/session/status/status.py | expected OK
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1630162
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=377553855&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eEXbsVwmRmyJ94vzdvM78A/runs/0/artifacts/public/logs/live_backing.log
[task 2022-05-10T13:28:26.888Z] 13:28:26 INFO - TEST-START | /webdriver/tests/bidi/session/status/status.py
[task 2022-05-10T13:28:26.939Z] 13:28:26 INFO - STDOUT: ============================= test session starts ==============================
[task 2022-05-10T13:28:26.940Z] 13:28:26 INFO - STDOUT: platform linux -- Python 3.6.9, pytest-6.1.1, py-1.5.2, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2022-05-10T13:28:26.941Z] 13:28:26 INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2022-05-10T13:28:26.942Z] 13:28:26 INFO - STDOUT: plugins: asyncio-0.14.0
[task 2022-05-10T13:28:26.943Z] 13:28:26 INFO - STDOUT: collecting ...
[task 2022-05-10T13:28:26.949Z] 13:28:26 INFO - STDOUT: collected 1 item
[task 2022-05-10T13:28:26.949Z] 13:28:26 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/session/status/status.py::test_bidi_session_status
[task 2022-05-10T13:28:26.953Z] 13:28:26 INFO - PID 1265 | 1652189306949 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmppldqhb7d.mozrunner"], "binary": "/builds/worker/workspace/build/application/firefox/firefox"}, "webSocketUrl": true}}}
[task 2022-05-10T13:28:26.954Z] 13:28:26 INFO - PID 1265 | 1652189306949 geckodriver::capabilities DEBUG Trying to read firefox version from ini files
[task 2022-05-10T13:28:26.957Z] 13:28:26 INFO - PID 1265 | 1652189306950 geckodriver::capabilities DEBUG Found version 102.0a1
[task 2022-05-10T13:28:26.958Z] 13:28:26 INFO - PID 1265 | 1652189306950 geckodriver::browser DEBUG Backing up prefs to "/tmp/tmppldqhb7d.mozrunner/user.geckodriver_backup"
[task 2022-05-10T13:28:26.959Z] 13:28:26 INFO - PID 1265 | 1652189306956 mozrunner::runner INFO Running command: "/builds/worker/workspace/build/application/firefox/firefox" "--marionette" "--profile" "/tmp/tmppldqhb7d.mozrunner" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-no-remote"
[task 2022-05-10T13:28:26.960Z] 13:28:26 INFO - PID 1265 | 1652189306957 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1
[task 2022-05-10T13:28:26.960Z] 13:28:26 INFO - PID 1265 | 1652189306957 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:26.961Z] 13:28:26 INFO - PID 1265 | 1652189306957 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:26.976Z] 13:28:26 INFO - PID 1265 | *** You are running in headless mode.
[task 2022-05-10T13:28:27.059Z] 13:28:27 INFO - PID 1265 | 1652189307058 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:27.059Z] 13:28:27 INFO - PID 1265 | 1652189307058 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:27.082Z] 13:28:27 INFO - PID 1265 | 1652189307081 RemoteAgent DEBUG WebDriver BiDi enabled
[task 2022-05-10T13:28:27.083Z] 13:28:27 INFO - PID 1265 | 1652189307082 RemoteAgent DEBUG CDP enabled
[task 2022-05-10T13:28:27.083Z] 13:28:27 INFO - PID 1265 | 1652189307082 Marionette INFO Marionette enabled
[task 2022-05-10T13:28:27.084Z] 13:28:27 INFO - PID 1265 | 1652189307082 RemoteAgent TRACE Received observer notification final-ui-startup
[task 2022-05-10T13:28:27.084Z] 13:28:27 INFO - PID 1265 | WebDriver BiDi listening on ws://localhost:9222
[task 2022-05-10T13:28:27.097Z] 13:28:27 INFO - PID 1265 | 1652189307097 CDP DEBUG Waiting for initial application window to be loaded
[task 2022-05-10T13:28:27.159Z] 13:28:27 INFO - PID 1265 | 1652189307158 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:27.159Z] 13:28:27 INFO - PID 1265 | 1652189307158 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:27.260Z] 13:28:27 INFO - PID 1265 | 1652189307258 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:27.260Z] 13:28:27 INFO - PID 1265 | 1652189307258 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:27.311Z] 13:28:27 INFO - PID 1265 | [GFX1-]: RenderCompositorSWGL failed mapping default framebuffer, no dt
[task 2022-05-10T13:28:27.359Z] 13:28:27 INFO - PID 1265 | 1652189307358 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:27.360Z] 13:28:27 INFO - PID 1265 | 1652189307358 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:27.460Z] 13:28:27 INFO - PID 1265 | 1652189307458 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:27.460Z] 13:28:27 INFO - PID 1265 | 1652189307458 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:27.560Z] 13:28:27 INFO - PID 1265 | 1652189307558 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:27.560Z] 13:28:27 INFO - PID 1265 | 1652189307558 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:27.660Z] 13:28:27 INFO - PID 1265 | 1652189307658 geckodriver::browser TRACE Failed to open /tmp/tmppldqhb7d.mozrunner/MarionetteActivePort
[task 2022-05-10T13:28:27.661Z] 13:28:27 INFO - PID 1265 | 1652189307658 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-05-10T13:28:27.716Z] 13:28:27 INFO - PID 1265 | 1652189307715 Marionette TRACE Received observer notification marionette-startup-requested
[task 2022-05-10T13:28:27.717Z] 13:28:27 INFO - PID 1265 | 1652189307715 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2022-05-10T13:28:27.734Z] 13:28:27 INFO - PID 1265 | 1652189307733 Marionette TRACE All scripts recorded.
[task 2022-05-10T13:28:27.735Z] 13:28:27 INFO - PID 1265 | 1652189307734 Marionette INFO Listening on port 42552
[task 2022-05-10T13:28:27.736Z] 13:28:27 INFO - PID 1265 | 1652189307734 Marionette DEBUG Marionette is listening
[task 2022-05-10T13:28:27.751Z] 13:28:27 INFO - PID 1265 | 1652189307750 RemoteAgent TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2022-05-10T13:28:27.752Z] 13:28:27 INFO - PID 1265 | DevTools listening on ws://localhost:9222/devtools/browser/d8b803b8-b2e2-4b4d-9e0f-860c17a69d1c
[task 2022-05-10T13:28:27.759Z] 13:28:27 INFO - PID 1265 | Read port: 42552
[task 2022-05-10T13:28:27.761Z] 13:28:27 INFO - PID 1265 | 1652189307761 Marionette DEBUG Accepted connection 0 from 127.0.0.1:59012
[task 2022-05-10T13:28:27.762Z] 13:28:27 INFO - PID 1265 | 1652189307761 geckodriver::marionette DEBUG Connection to Marionette established on 127.0.0.1:42552.
[task 2022-05-10T13:28:27.764Z] 13:28:27 INFO - PID 1265 | 1652189307763 Marionette DEBUG 0 -> [0,1,"WebDriver:NewSession",{"webSocketUrl":true}]
[task 2022-05-10T13:28:27.765Z] 13:28:27 INFO - PID 1265 | 1652189307764 WebDriver BiDi DEBUG Waiting for initial application window to be loaded
[task 2022-05-10T13:28:27.766Z] 13:28:27 INFO - PID 1265 | 1652189307764 WebDriver BiDi DEBUG Registered session handler: /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0
[task 2022-05-10T13:28:27.768Z] 13:28:27 INFO - PID 1265 | 1652189307767 RemoteAgent TRACE [30] Document already finished loading: about:blank
[task 2022-05-10T13:28:27.772Z] 13:28:27 INFO - PID 1265 | 1652189307770 Marionette DEBUG 0 <- [1,1,null,{"sessionId":"b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0","capabilities":{"browserName":"firefox","browserVersion":"102.0 ... d.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-05-10T13:28:27.774Z] 13:28:27 INFO - PID 1265 | 1652189307772 webdriver::server DEBUG <- 200 OK {"value":{"sessionId":"b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"102.0a1","moz:accessibilityChecks":false,"moz:buildID":"20220510130732","moz:debuggerAddress":"localhost:9222","moz:geckodriverVersion":"0.31.0","moz:headless":true,"moz:processID":5667,"moz:profile":"/tmp/tmppldqhb7d.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"pageLoadStrategy":"normal","platformName":"linux","platformVersion":"4.4.0-1014-aws","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify","webSocketUrl":"ws://localhost:9222/session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0"}}}
[task 2022-05-10T13:28:27.778Z] 13:28:27 INFO - PID 1265 | 1652189307776 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window/rect {"width": 800, "height": 600}
[task 2022-05-10T13:28:27.779Z] 13:28:27 INFO - PID 1265 | 1652189307776 RemoteAgent DEBUG WebDriverBiDiConnection ea93691c-ada4-4c00-aed4-a35f124f9eb1 accepted
[task 2022-05-10T13:28:27.779Z] 13:28:27 INFO - PID 1265 | 1652189307776 WebDriver BiDi DEBUG Connection ea93691c-ada4-4c00-aed4-a35f124f9eb1 attached to session b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0
[task 2022-05-10T13:28:27.779Z] 13:28:27 INFO - PID 1265 | 1652189307777 Marionette DEBUG 0 -> [0,2,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2022-05-10T13:28:27.779Z] 13:28:27 INFO - PID 1265 | 1652189307777 Marionette DEBUG 0 <- [1,2,null,{"x":0,"y":0,"width":800,"height":600}]
[task 2022-05-10T13:28:27.779Z] 13:28:27 INFO - PID 1265 | 1652189307777 webdriver::server DEBUG <- 200 OK {"value":{"x":0,"y":0,"width":800,"height":600}}
[task 2022-05-10T13:28:27.780Z] 13:28:27 INFO - PID 1265 | 1652189307778 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window/rect {"x": 100, "y": 100}
[task 2022-05-10T13:28:27.780Z] 13:28:27 INFO - PID 1265 | 1652189307779 Marionette DEBUG 0 -> [0,3,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2022-05-10T13:28:27.784Z] 13:28:27 INFO - PID 1265 | 1652189307783 Marionette DEBUG 0 <- [1,3,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2022-05-10T13:28:27.784Z] 13:28:27 INFO - PID 1265 | 1652189307783 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2022-05-10T13:28:27.785Z] 13:28:27 INFO - PID 1265 | 1652189307784 WebDriver BiDi DEBUG b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0 -> {"id":1,"method":"session.status","params":{}}
[task 2022-05-10T13:28:27.786Z] 13:28:27 INFO - PID 1265 | 1652189307784 WebDriver BiDi DEBUG b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0 <- {"id":1,"result":{"ready":false,"message":"Session already started"}}
[task 2022-05-10T13:28:27.787Z] 13:28:27 INFO - STDOUT: PASSED
[task 2022-05-10T13:28:27.787Z] 13:28:27 INFO - PID 1265 | 1652189307786 RemoteAgent DEBUG WebDriverBiDiConnection ea93691c-ada4-4c00-aed4-a35f124f9eb1 closed
[task 2022-05-10T13:28:27.788Z] 13:28:27 INFO - PID 1265 | 1652189307787 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/timeouts {"implicit": 0}
[task 2022-05-10T13:28:27.788Z] 13:28:27 INFO - PID 1265 | 1652189307788 Marionette DEBUG 0 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-05-10T13:28:27.789Z] 13:28:27 INFO - PID 1265 | 1652189307788 Marionette DEBUG 0 <- [1,4,null,{"value":null}]
[task 2022-05-10T13:28:27.790Z] 13:28:27 INFO - PID 1265 | 1652189307788 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-05-10T13:28:27.790Z] 13:28:27 INFO - PID 1265 | 1652189307789 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/timeouts {"pageLoad": 300000}
[task 2022-05-10T13:28:27.791Z] 13:28:27 INFO - PID 1265 | 1652189307790 Marionette DEBUG 0 -> [0,5,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-05-10T13:28:27.791Z] 13:28:27 INFO - PID 1265 | 1652189307790 Marionette DEBUG 0 <- [1,5,null,{"value":null}]
[task 2022-05-10T13:28:27.792Z] 13:28:27 INFO - PID 1265 | 1652189307790 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-05-10T13:28:27.793Z] 13:28:27 INFO - PID 1265 | 1652189307791 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/timeouts {"script": 30000}
[task 2022-05-10T13:28:27.793Z] 13:28:27 INFO - PID 1265 | 1652189307792 Marionette DEBUG 0 -> [0,6,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-05-10T13:28:27.794Z] 13:28:27 INFO - PID 1265 | 1652189307792 Marionette DEBUG 0 <- [1,6,null,{"value":null}]
[task 2022-05-10T13:28:27.794Z] 13:28:27 INFO - PID 1265 | 1652189307792 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-05-10T13:28:27.795Z] 13:28:27 INFO - PID 1265 | 1652189307793 webdriver::server DEBUG -> GET /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window
[task 2022-05-10T13:28:27.795Z] 13:28:27 INFO - PID 1265 | 1652189307793 Marionette DEBUG 0 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2022-05-10T13:28:27.795Z] 13:28:27 INFO - PID 1265 | 1652189307794 Marionette DEBUG 0 <- [1,7,null,{"value":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}]
[task 2022-05-10T13:28:27.796Z] 13:28:27 INFO - PID 1265 | 1652189307794 webdriver::server DEBUG <- 200 OK {"value":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}
[task 2022-05-10T13:28:27.797Z] 13:28:27 INFO - PID 1265 | 1652189307795 webdriver::server DEBUG -> GET /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window
[task 2022-05-10T13:28:27.797Z] 13:28:27 INFO - PID 1265 | 1652189307796 Marionette DEBUG 0 -> [0,8,"WebDriver:GetWindowHandle",{}]
[task 2022-05-10T13:28:27.798Z] 13:28:27 INFO - PID 1265 | 1652189307796 Marionette DEBUG 0 <- [1,8,null,{"value":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}]
[task 2022-05-10T13:28:27.798Z] 13:28:27 INFO - PID 1265 | 1652189307796 webdriver::server DEBUG <- 200 OK {"value":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}
[task 2022-05-10T13:28:27.799Z] 13:28:27 INFO - PID 1265 | 1652189307797 webdriver::server DEBUG -> GET /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window/handles
[task 2022-05-10T13:28:27.799Z] 13:28:27 INFO - PID 1265 | 1652189307797 Marionette DEBUG 0 -> [0,9,"WebDriver:GetWindowHandles",{}]
[task 2022-05-10T13:28:27.800Z] 13:28:27 INFO - PID 1265 | 1652189307797 Marionette DEBUG 0 <- [1,9,null,["c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"]]
[task 2022-05-10T13:28:27.801Z] 13:28:27 INFO - PID 1265 | 1652189307798 webdriver::server DEBUG <- 200 OK {"value":["c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"]}
[task 2022-05-10T13:28:27.802Z] 13:28:27 INFO - PID 1265 | 1652189307799 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window {"handle": "c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}
[task 2022-05-10T13:28:27.803Z] 13:28:27 INFO - PID 1265 | 1652189307799 Marionette DEBUG 0 -> [0,10,"WebDriver:SwitchToWindow",{"handle":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}]
[task 2022-05-10T13:28:27.804Z] 13:28:27 INFO - PID 1265 | 1652189307800 Marionette DEBUG 0 <- [1,10,null,{"value":null}]
[task 2022-05-10T13:28:27.804Z] 13:28:27 INFO - PID 1265 | 1652189307800 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-05-10T13:28:27.804Z] 13:28:27 INFO - PID 1265 | 1652189307801 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/alert/dismiss {}
[task 2022-05-10T13:28:27.804Z] 13:28:27 INFO - PID 1265 | 1652189307802 Marionette DEBUG 0 -> [0,11,"WebDriver:DismissAlert",{}]
[task 2022-05-10T13:28:27.805Z] 13:28:27 INFO - PID 1265 | 1652189307802 Marionette DEBUG 0 <- [1,11,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm: ... ote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"},null]
[task 2022-05-10T13:28:27.806Z] 13:28:27 INFO - PID 1265 | 1652189307802 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:183:5\nNoSuchAlertError@chrome://remote/content/shared/webdriver/Errors.jsm:384:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://remote/content/marionette/driver.js:2508:11\nGeckoDriver.prototype.dismissDialog@chrome://remote/content/marionette/driver.js:2419:8\ndespatch@chrome://remote/content/marionette/server.js:306:40\nexecute@chrome://remote/content/marionette/server.js:279:16\nonPacket/<@chrome://remote/content/marionette/server.js:252:20\nonPacket@chrome://remote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"}}
[task 2022-05-10T13:28:27.809Z] 13:28:27 INFO - PID 1265 | 1652189307803 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window {"handle": "c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}
[task 2022-05-10T13:28:27.809Z] 13:28:27 INFO - PID 1265 | 1652189307803 Marionette DEBUG 0 -> [0,12,"WebDriver:SwitchToWindow",{"handle":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}]
[task 2022-05-10T13:28:27.809Z] 13:28:27 INFO - PID 1265 | 1652189307804 Marionette DEBUG 0 <- [1,12,null,{"value":null}]
[task 2022-05-10T13:28:27.809Z] 13:28:27 INFO - PID 1265 | 1652189307804 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-05-10T13:28:27.809Z] 13:28:27 INFO - PID 1265 | 1652189307805 webdriver::server DEBUG -> GET /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window
[task 2022-05-10T13:28:27.809Z] 13:28:27 INFO - PID 1265 | 1652189307805 Marionette DEBUG 0 -> [0,13,"WebDriver:GetWindowHandle",{}]
[task 2022-05-10T13:28:27.809Z] 13:28:27 INFO - PID 1265 | 1652189307806 Marionette DEBUG 0 <- [1,13,null,{"value":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}]
[task 2022-05-10T13:28:27.810Z] 13:28:27 INFO - PID 1265 | 1652189307806 webdriver::server DEBUG <- 200 OK {"value":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}
[task 2022-05-10T13:28:27.810Z] 13:28:27 INFO - PID 1265 | 1652189307806 webdriver::server DEBUG -> GET /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window/handles
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307807 Marionette DEBUG 0 -> [0,14,"WebDriver:GetWindowHandles",{}]
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307807 Marionette DEBUG 0 <- [1,14,null,["c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"]]
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307807 webdriver::server DEBUG <- 200 OK {"value":["c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"]}
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307808 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window {"handle": "c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307808 Marionette DEBUG 0 -> [0,15,"WebDriver:SwitchToWindow",{"handle":"c2c9f069-4c34-427d-80c1-6c77f6bcc6a5"}]
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307808 Marionette DEBUG 0 <- [1,15,null,{"value":null}]
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307808 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307810 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/window/rect {"width": 800, "height": 600}
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307810 Marionette DEBUG 0 -> [0,16,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307811 Marionette DEBUG 0 <- [1,16,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2022-05-10T13:28:27.814Z] 13:28:27 INFO - PID 1265 | 1652189307811 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2022-05-10T13:28:27.815Z] 13:28:27 INFO - PID 1265 | 1652189307811 webdriver::server DEBUG -> POST /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0/frame {"id": null}
[task 2022-05-10T13:28:27.815Z] 13:28:27 INFO - PID 1265 | 1652189307812 Marionette DEBUG 0 -> [0,17,"WebDriver:SwitchToFrame",{"id":null}]
[task 2022-05-10T13:28:27.816Z] 13:28:27 INFO - PID 1265 | 1652189307816 Marionette TRACE [30] MarionetteCommands actor created for window id 4294967297
[task 2022-05-10T13:28:27.820Z] 13:28:27 INFO - PID 1265 | 1652189307819 Marionette DEBUG 0 <- [1,17,null,{"value":null}]
[task 2022-05-10T13:28:27.820Z] 13:28:27 INFO - PID 1265 | 1652189307819 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-05-10T13:28:27.822Z] 13:28:27 INFO - PID 1265 | 1652189307821 webdriver::server DEBUG -> DELETE /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0
[task 2022-05-10T13:28:27.822Z] 13:28:27 INFO - PID 1265 | 1652189307821 Marionette DEBUG 0 -> [0,18,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2022-05-10T13:28:27.822Z] 13:28:27 INFO - PID 1265 | 1652189307821 Marionette INFO Stopped listening on port 42552
[task 2022-05-10T13:28:27.824Z] 13:28:27 INFO - PID 1265 | 1652189307823 WebDriver BiDi DEBUG Unregistered session handler: /session/b9cf1bbc-21b8-4bb2-8c44-5fa709b5d1c0
[task 2022-05-10T13:28:27.851Z] 13:28:27 INFO - PID 1265 | 1652189307850 Marionette TRACE Received observer notification quit-application
[task 2022-05-10T13:28:27.852Z] 13:28:27 INFO - PID 1265 | 1652189307850 Marionette TRACE Received observer notification quit-application
[task 2022-05-10T13:28:27.852Z] 13:28:27 INFO - PID 1265 | 1652189307850 Marionette DEBUG Marionette stopped listening
[task 2022-05-10T13:28:27.854Z] 13:28:27 INFO - PID 1265 | 1652189307854 RemoteAgent TRACE Received observer notification quit-application
[task 2022-05-10T13:28:27.855Z] 13:28:27 INFO - PID 1265 | 1652189307854 Marionette DEBUG 0 <- [1,18,null,{"cause":"shutdown","forced":false}]
[task 2022-05-10T13:28:27.867Z] 13:28:27 INFO - PID 1265 | 1652189307865 RemoteAgent DEBUG Resetting recommended pref browser.contentblocking.features.standard
[task 2022-05-10T13:28:27.868Z] 13:28:27 INFO - PID 1265 | 1652189307866 RemoteAgent DEBUG Resetting recommended pref network.cookie.cookieBehavior
[task 2022-05-10T13:28:27.870Z] 13:28:27 INFO - PID 1265 | 1652189307866 webdriver::server DEBUG Teardown session
[task 2022-05-10T13:28:27.872Z] 13:28:27 INFO - PID 1265 | 1652189307867 Marionette DEBUG Closed connection 0
[task 2022-05-10T13:28:27.981Z] 13:28:27 INFO - PID 1265 | [2022-05-10T13:28:27Z ERROR xulstore::persist] removeDocument error: unavailable
[task 2022-05-10T13:28:56.890Z] 13:28:56 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/session/status/status.py | expected OK
[task 2022-05-10T13:28:56.890Z] 13:28:56 INFO - TEST-INFO took 30002ms
[task 2022-05-10T13:28:56.975Z] 13:28:56 INFO - Closing logging queue
[task 2022-05-10T13:28:56.977Z] 13:28:56 WARNING - Dropping log message: %r
[task 2022-05-10T13:28:56.978Z] 13:28:56 INFO - queue closed
[task 2022-05-10T13:28:56.983Z] 13:28:56 INFO - PID 5838 | 1652189336981 geckodriver INFO Listening on 127.0.0.1:47143
[task 2022-05-10T13:28:56.983Z] 13:28:56 INFO - Starting runner
|
||
Comment 1•2 years ago
|
||
Shutdown hang of Firefox.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → DUPLICATE
|
||
Comment 2•2 years 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
•