Closed
Bug 1783262
Opened 2 years ago
Closed 2 years ago
Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | mozilla/tests/webdriver/bidi/browsing_context/navigate/error.py | expected OK
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1783217
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, Whiteboard: [retriggered])
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=386438189&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/D4QigaYTTGeZ1e8HJOQt1A/runs/0/artifacts/public/logs/live_backing.log
[task 2022-08-04T18:19:41.354Z] 18:19:41 INFO - TEST-START | /_mozilla/webdriver/bidi/browsing_context/navigate/error.py
[task 2022-08-04T18:19:42.047Z] 18:19:42 INFO - STDOUT: ============================= test session starts =============================
[task 2022-08-04T18:19:42.058Z] 18:19:42 INFO - STDOUT: platform win32 -- Python 3.9.5, pytest-7.0.1, pluggy-unknown -- Z:\task_165963467511879\build\venv\Scripts\python.exe
[task 2022-08-04T18:19:42.060Z] 18:19:42 INFO - STDOUT: rootdir: Z:\task_165963467511879\build
[task 2022-08-04T18:19:42.061Z] 18:19:42 INFO - STDOUT: plugins: asyncio-0.14.0
[task 2022-08-04T18:19:42.062Z] 18:19:42 INFO - STDOUT: collecting ...
[task 2022-08-04T18:19:42.104Z] 18:19:42 INFO - STDOUT: collected 2 items
[task 2022-08-04T18:19:42.119Z] 18:19:42 INFO - STDOUT: tests/web-platform/mozilla/tests/webdriver/bidi/browsing_context/navigate/error.py::test_insecure_certificate
[task 2022-08-04T18:19:43.521Z] 18:19:43 INFO - STDOUT: Running command: ['Z:\\task_165963467511879\\fetches\\geckodriver.exe', '--port', '60883', '-vv']
[task 2022-08-04T18:19:43.546Z] 18:19:43 INFO - STDOUT: 1659637183548 geckodriver INFO Listening on 127.0.0.1:60883
[task 2022-08-04T18:19:44.047Z] 18:19:44 INFO - STDOUT: 1659637184048 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "C:\\Users\\task_165963467511879\\AppData\\Local\\Temp\\tmpdmfajwld"], "binary": "Z:\\task_165963467511879\\build\\application\\firefox\\firefox.exe"}, "acceptInsecureCerts": false, "webSocketUrl": true}}}
[task 2022-08-04T18:19:44.051Z] 18:19:44 INFO - STDOUT: 1659637184048 geckodriver::capabilities DEBUG Trying to read firefox version from ini files
[task 2022-08-04T18:19:44.052Z] 18:19:44 INFO - STDOUT: 1659637184049 geckodriver::capabilities DEBUG Found version 105.0a1
[task 2022-08-04T18:19:44.058Z] 18:19:44 INFO - STDOUT: 1659637184056 geckodriver::browser DEBUG Backing up prefs to "C:\\Users\\task_165963467511879\\AppData\\Local\\Temp\\tmpdmfajwld\\user.geckodriver_backup"
[task 2022-08-04T18:19:44.069Z] 18:19:44 INFO - STDOUT: 1659637184071 mozrunner::runner INFO Running command: "Z:\\task_165963467511879\\build\\application\\firefox\\firefox.exe" "--marionette" "--profile" "C:\\Users\\task_165963467511879\\AppData\\Local\\Temp\\tmpdmfajwld" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-no-remote"
[task 2022-08-04T18:19:44.131Z] 18:19:44 INFO - STDOUT: 1659637184135 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1
[task 2022-08-04T18:19:44.139Z] 18:19:44 INFO - STDOUT: 1659637184135 geckodriver::browser TRACE Failed to open C:\Users\task_165963467511879\AppData\Local\Temp\tmpdmfajwld\MarionetteActivePort
[task 2022-08-04T18:19:44.140Z] 18:19:44 INFO - STDOUT: 1659637184135 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-08-04T18:19:44.245Z] 18:19:44 INFO - STDOUT: *** You are running in headless mode.
[task 2022-08-04T18:19:44.247Z] 18:19:44 INFO - STDOUT: 1659637184245 geckodriver::browser TRACE Failed to open C:\Users\task_165963467511879\AppData\Local\Temp\tmpdmfajwld\MarionetteActivePort
[task 2022-08-04T18:19:44.248Z] 18:19:44 INFO - STDOUT: 1659637184245 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-08-04T18:19:44.353Z] 18:19:44 INFO - STDOUT: 1659637184353 geckodriver::browser TRACE Failed to open C:\Users\task_165963467511879\AppData\Local\Temp\tmpdmfajwld\MarionetteActivePort
[task 2022-08-04T18:19:44.356Z] 18:19:44 INFO - STDOUT: 1659637184353 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-08-04T18:19:44.461Z] 18:19:44 INFO - STDOUT: 1659637184461 geckodriver::browser TRACE Failed to open C:\Users\task_165963467511879\AppData\Local\Temp\tmpdmfajwld\MarionetteActivePort
[task 2022-08-04T18:19:44.464Z] 18:19:44 INFO - STDOUT: 1659637184462 geckodriver::marionette TRACE Retrying in 100ms
[task 2022-08-04T18:19:44.538Z] 18:19:44 INFO - STDOUT: 1659637184546 RemoteAgent DEBUG WebDriver BiDi enabled
[task 2022-08-04T18:19:44.549Z] 18:19:44 INFO - STDOUT: 1659637184547 RemoteAgent DEBUG CDP enabled
[task 2022-08-04T18:19:44.549Z] 18:19:44 INFO - STDOUT: 1659637184548 Marionette INFO Marionette enabled
[task 2022-08-04T18:19:44.550Z] 18:19:44 INFO - STDOUT: 1659637184550 Marionette TRACE Received observer notification final-ui-startup
[task 2022-08-04T18:19:44.552Z] 18:19:44 INFO - STDOUT: 1659637184551 Marionette INFO Listening on port 60888
[task 2022-08-04T18:19:44.553Z] 18:19:44 INFO - STDOUT: 1659637184551 Marionette DEBUG Marionette is listening
[task 2022-08-04T18:19:44.553Z] 18:19:44 INFO - STDOUT: 1659637184552 RemoteAgent TRACE Received observer notification final-ui-startup
[task 2022-08-04T18:19:44.564Z] 18:19:44 INFO - STDOUT: WebDriver BiDi listening on ws://localhost:9222
[task 2022-08-04T18:19:44.569Z] 18:19:44 INFO - STDOUT: 1659637184567 CDP DEBUG Waiting for initial application window
[task 2022-08-04T18:19:44.570Z] 18:19:44 INFO - STDOUT: Read port: 60888
[task 2022-08-04T18:19:44.612Z] 18:19:44 INFO - STDOUT: 1659637184623 Marionette DEBUG Accepted connection 0 from 127.0.0.1:60889
[task 2022-08-04T18:19:44.629Z] 18:19:44 INFO - STDOUT: 1659637184627 geckodriver::marionette DEBUG Connection to Marionette established on 127.0.0.1:60888.
[task 2022-08-04T18:19:44.637Z] 18:19:44 INFO - STDOUT: 1659637184635 Marionette DEBUG 0 -> [0,1,"WebDriver:NewSession",{"acceptInsecureCerts":false,"webSocketUrl":true}]
[task 2022-08-04T18:19:44.643Z] 18:19:44 INFO - STDOUT: 1659637184641 WebDriver BiDi DEBUG Waiting for initial application window
[task 2022-08-04T18:19:45.225Z] 18:19:45 INFO - STDOUT: [GFX1-]: RenderCompositorSWGL failed mapping default framebuffer, no dt
[task 2022-08-04T18:19:46.228Z] 18:19:46 INFO - STDOUT: 1659637186229 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2022-08-04T18:19:46.231Z] 18:19:46 INFO - STDOUT: 1659637186229 RemoteAgent TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2022-08-04T18:19:46.241Z] 18:19:46 INFO - STDOUT: 1659637186239 WebDriver BiDi DEBUG Registered session handler: /session/c703b9a5-ece9-4fc2-b1e8-5e422e2899b0
[task 2022-08-04T18:19:46.242Z] 18:19:46 INFO - STDOUT: 1659637186239 Marionette DEBUG Waiting for initial application window
[task 2022-08-04T18:19:46.243Z] 18:19:46 INFO - STDOUT: DevTools listening on ws://localhost:9222/devtools/browser/5e0e1b53-e1b7-4eea-ab86-0ce19f8e2fff
[task 2022-08-04T18:19:46.247Z] 18:19:46 INFO - STDOUT: 1659637186245 RemoteAgent TRACE [3] Document already finished loading: about:blank
[task 2022-08-04T18:19:46.259Z] 18:19:46 INFO - STDOUT: 1659637186257 Marionette DEBUG 0 <- [1,1,null,{"sessionId":"c703b9a5-ece9-4fc2-b1e8-5e422e2899b0","capabilities":{"browserName":"firefox","browserVersion":"105.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-08-04T18:19:46.260Z] 18:19:46 INFO - STDOUT: 1659637186263 webdriver::server DEBUG <- 200 OK {"value":{"sessionId":"c703b9a5-ece9-4fc2-b1e8-5e422e2899b0","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"105.0a1","moz:accessibilityChecks":false,"moz:buildID":"20220804154332","moz:debuggerAddress":"localhost:9222","moz:geckodriverVersion":"0.31.0","moz:headless":true,"moz:platformVersion":"10.0","moz:processID":7396,"moz:profile":"C:\\Users\\task_165963467511879\\AppData\\Local\\Temp\\tmpdmfajwld","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"pageLoadStrategy":"normal","platformName":"windows","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify","webSocketUrl":"ws://localhost:9222/session/c703b9a5-ece9-4fc2-b1e8-5e422e2899b0"}}}
[task 2022-08-04T18:19:46.285Z] 18:19:46 INFO - STDOUT: 1659637186285 RemoteAgent DEBUG WebDriverBiDiConnection c5602067-178c-484e-a685-84703bde10a1 accepted
[task 2022-08-04T18:19:46.288Z] 18:19:46 INFO - STDOUT: 1659637186285 WebDriver BiDi DEBUG Connection c5602067-178c-484e-a685-84703bde10a1 attached to session c703b9a5-ece9-4fc2-b1e8-5e422e2899b0
[task 2022-08-04T18:19:46.289Z] 18:19:46 INFO - STDOUT: 1659637186286 WebDriver BiDi DEBUG c703b9a5-ece9-4fc2-b1e8-5e422e2899b0 -> {"id":1,"method":"browsingContext.getTree","params":{"maxDepth":0}}
[task 2022-08-04T18:19:46.297Z] 18:19:46 INFO - STDOUT: 1659637186296 RemoteAgent TRACE Registered MessageHandlerFrame actors
[task 2022-08-04T18:19:46.298Z] 18:19:46 INFO - STDOUT: 1659637186297 RemoteAgent TRACE Created MessageHandler ROOT for session c703b9a5-ece9-4fc2-b1e8-5e422e2899b0
[task 2022-08-04T18:19:46.303Z] 18:19:46 INFO - STDOUT: 1659637186302 RemoteAgent TRACE Received command browsingContext.getTree for destination ROOT
[task 2022-08-04T18:19:46.304Z] 18:19:46 INFO - STDOUT: 1659637186303 RemoteAgent TRACE Module root/browsingContext.jsm found for ROOT
[task 2022-08-04T18:19:46.305Z] 18:19:46 INFO - STDOUT: 1659637186304 WebDriver BiDi DEBUG c703b9a5-ece9-4fc2-b1e8-5e422e2899b0 <- {"id":1,"result":{"contexts":[{"context":"c16436ff-2d19-40ae-a02c-d643ba33e6b2","url":"about:blank","children":null,"parent":null}]}}
[task 2022-08-04T18:19:46.308Z] 18:19:46 INFO - STDOUT: 1659637186306 WebDriver BiDi DEBUG c703b9a5-ece9-4fc2-b1e8-5e422e2899b0 -> {"id":2,"method":"browsingContext.navigate","params":{"context":"c16436ff-2d19-40ae-a02c-d643ba33e6b2","url":"https://web-platform.test:8443/common/blank.html","wait":"complete"}}
[task 2022-08-04T18:19:46.308Z] 18:19:46 INFO - STDOUT: 1659637186306 RemoteAgent TRACE Received command browsingContext.navigate for destination ROOT
[task 2022-08-04T18:19:46.309Z] 18:19:46 INFO - STDOUT: 1659637186307 RemoteAgent TRACE Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2022-08-04T18:19:46.310Z] 18:19:46 INFO - STDOUT: 1659637186308 RemoteAgent TRACE Module windowglobal-in-root/browsingContext.jsm not found for WINDOW_GLOBAL
[task 2022-08-04T18:19:46.323Z] 18:19:46 INFO - STDOUT: 1659637186321 RemoteAgent TRACE Created MessageHandler WINDOW_GLOBAL for session c703b9a5-ece9-4fc2-b1e8-5e422e2899b0
[task 2022-08-04T18:19:46.323Z] 18:19:46 INFO - STDOUT: 1659637186321 RemoteAgent TRACE Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2022-08-04T18:19:46.326Z] 18:19:46 INFO - STDOUT: 1659637186325 RemoteAgent TRACE Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2022-08-04T18:19:46.336Z] 18:19:46 INFO - STDOUT: 1659637186335 RemoteAgent TRACE [3] ProgressListener state=start: https://web-platform.test:8443/common/blank.html
[task 2022-08-04T18:19:46.348Z] 18:19:46 INFO - STDOUT: 1659637186358 Marionette TRACE Remoteness change detected. Set new top-level browsing context to 11
[task 2022-08-04T18:19:46.388Z] 18:19:46 INFO - STDOUT: 1659637186389 Marionette WARN Ignoring event 'pageshow' because document has an invalid readyState of 'uninitialized'.
[task 2022-08-04T18:19:46.403Z] 18:19:46 INFO - STDOUT: 1659637186404 RemoteAgent TRACE [11] ProgressListener state=stop: error=0x805a1ff3 (NS_ERROR_GENERATE_FAILURE(NS_ERROR_MODULE_SECURITY, SEC_ERROR_UNKNOWN_ISSUER))
[task 2022-08-04T18:19:46.408Z] 18:19:46 INFO - STDOUT: 1659637186405 WebDriver BiDi DEBUG c703b9a5-ece9-4fc2-b1e8-5e422e2899b0 <- {"id":2,"error":"unknown error","message":"Error: NS_ERROR_GENERATE_FAILURE(NS_ERROR_MODULE_SECURITY, SEC_ERROR_UNKNOWN_ISSUE ... tate@chrome://remote/content/shared/Navigate.jsm:239:28\nonStateChange@chrome://remote/content/shared/Navigate.jsm:284:28\n"}
[task 2022-08-04T18:19:46.409Z] 18:19:46 INFO - STDOUT: PASSED
<...>
[task 2022-08-04T18:20:20.587Z] 18:20:20 INFO - PID 6380 | 1659637220587 webdriver::server DEBUG -> GET /session/1bab0f06-0616-43bf-b570-ffd308097624/window/handles
[task 2022-08-04T18:20:20.588Z] 18:20:20 INFO - PID 6380 | 1659637220587 Marionette DEBUG 0 -> [0,9,"WebDriver:GetWindowHandles",{}]
[task 2022-08-04T18:20:20.589Z] 18:20:20 INFO - PID 6380 | 1659637220588 Marionette DEBUG 0 <- [1,9,null,["00855826-291a-4f1d-873f-a707eb99cf18"]]
[task 2022-08-04T18:20:20.590Z] 18:20:20 INFO - PID 6380 | 1659637220589 webdriver::server DEBUG <- 200 OK {"value":["00855826-291a-4f1d-873f-a707eb99cf18"]}
[task 2022-08-04T18:20:20.591Z] 18:20:20 INFO - PID 6380 | 1659637220590 webdriver::server DEBUG -> POST /session/1bab0f06-0616-43bf-b570-ffd308097624/window {"handle": "00855826-291a-4f1d-873f-a707eb99cf18"}
[task 2022-08-04T18:20:20.592Z] 18:20:20 INFO - PID 6380 | 1659637220592 Marionette DEBUG 0 -> [0,10,"WebDriver:SwitchToWindow",{"handle":"00855826-291a-4f1d-873f-a707eb99cf18"}]
[task 2022-08-04T18:20:20.594Z] 18:20:20 INFO - PID 6380 | 1659637220593 Marionette DEBUG 0 <- [1,10,null,{"value":null}]
[task 2022-08-04T18:20:20.595Z] 18:20:20 INFO - PID 6380 | 1659637220595 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-08-04T18:20:20.597Z] 18:20:20 INFO - PID 6380 | 1659637220596 webdriver::server DEBUG -> POST /session/1bab0f06-0616-43bf-b570-ffd308097624/alert/dismiss {}
[task 2022-08-04T18:20:20.598Z] 18:20:20 INFO - PID 6380 | 1659637220598 Marionette DEBUG 0 -> [0,11,"WebDriver:DismissAlert",{}]
[task 2022-08-04T18:20:20.599Z] 18:20:20 INFO - PID 6380 | 1659637220599 Marionette DEBUG 0 <- [1,11,{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.jsm:12:1\nWe ... ote/content/marionette/server.js:251:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:502:20\n"},null]
[task 2022-08-04T18:20:20.604Z] 18:20:20 INFO - PID 6380 | 1659637220601 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:2665:11\nGeckoDriver.prototype.dismissDialog@chrome://remote/content/marionette/driver.js:2572: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-08-04T18:20:20.605Z] 18:20:20 INFO - PID 6380 | 1659637220602 webdriver::server DEBUG -> POST /session/1bab0f06-0616-43bf-b570-ffd308097624/window {"handle": "00855826-291a-4f1d-873f-a707eb99cf18"}
[task 2022-08-04T18:20:20.606Z] 18:20:20 INFO - PID 6380 | 1659637220603 Marionette DEBUG 0 -> [0,12,"WebDriver:SwitchToWindow",{"handle":"00855826-291a-4f1d-873f-a707eb99cf18"}]
[task 2022-08-04T18:20:20.607Z] 18:20:20 INFO - PID 6380 | 1659637220604 Marionette DEBUG 0 <- [1,12,null,{"value":null}]
[task 2022-08-04T18:20:20.607Z] 18:20:20 INFO - PID 6380 | 1659637220605 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-08-04T18:20:20.608Z] 18:20:20 INFO - PID 6380 | 1659637220606 webdriver::server DEBUG -> GET /session/1bab0f06-0616-43bf-b570-ffd308097624/window
[task 2022-08-04T18:20:20.608Z] 18:20:20 INFO - PID 6380 | 1659637220607 Marionette DEBUG 0 -> [0,13,"WebDriver:GetWindowHandle",{}]
[task 2022-08-04T18:20:20.610Z] 18:20:20 INFO - PID 6380 | 1659637220607 Marionette DEBUG 0 <- [1,13,null,{"value":"00855826-291a-4f1d-873f-a707eb99cf18"}]
[task 2022-08-04T18:20:20.610Z] 18:20:20 INFO - PID 6380 | 1659637220609 webdriver::server DEBUG <- 200 OK {"value":"00855826-291a-4f1d-873f-a707eb99cf18"}
[task 2022-08-04T18:20:20.611Z] 18:20:20 INFO - PID 6380 | 1659637220610 webdriver::server DEBUG -> GET /session/1bab0f06-0616-43bf-b570-ffd308097624/window/handles
[task 2022-08-04T18:20:20.612Z] 18:20:20 INFO - PID 6380 | 1659637220611 Marionette DEBUG 0 -> [0,14,"WebDriver:GetWindowHandles",{}]
[task 2022-08-04T18:20:20.613Z] 18:20:20 INFO - PID 6380 | 1659637220611 Marionette DEBUG 0 <- [1,14,null,["00855826-291a-4f1d-873f-a707eb99cf18"]]
[task 2022-08-04T18:20:20.614Z] 18:20:20 INFO - PID 6380 | 1659637220612 webdriver::server DEBUG <- 200 OK {"value":["00855826-291a-4f1d-873f-a707eb99cf18"]}
[task 2022-08-04T18:20:20.614Z] 18:20:20 INFO - PID 6380 | 1659637220613 webdriver::server DEBUG -> POST /session/1bab0f06-0616-43bf-b570-ffd308097624/window {"handle": "00855826-291a-4f1d-873f-a707eb99cf18"}
[task 2022-08-04T18:20:20.615Z] 18:20:20 INFO - PID 6380 | 1659637220613 Marionette DEBUG 0 -> [0,15,"WebDriver:SwitchToWindow",{"handle":"00855826-291a-4f1d-873f-a707eb99cf18"}]
[task 2022-08-04T18:20:20.616Z] 18:20:20 INFO - PID 6380 | 1659637220615 Marionette DEBUG 0 <- [1,15,null,{"value":null}]
[task 2022-08-04T18:20:20.616Z] 18:20:20 INFO - PID 6380 | 1659637220615 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-08-04T18:20:20.617Z] 18:20:20 INFO - PID 6380 | 1659637220616 webdriver::server DEBUG -> POST /session/1bab0f06-0616-43bf-b570-ffd308097624/window/rect {"width": 800, "height": 600}
[task 2022-08-04T18:20:20.618Z] 18:20:20 INFO - PID 6380 | 1659637220616 Marionette DEBUG 0 -> [0,16,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2022-08-04T18:20:20.619Z] 18:20:20 INFO - PID 6380 | 1659637220617 Marionette TRACE Requested window geometry matches
[task 2022-08-04T18:20:20.619Z] 18:20:20 INFO - PID 6380 | 1659637220617 Marionette DEBUG 0 <- [1,16,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2022-08-04T18:20:20.620Z] 18:20:20 INFO - PID 6380 | 1659637220617 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2022-08-04T18:20:20.620Z] 18:20:20 INFO - PID 6380 | 1659637220618 webdriver::server DEBUG -> POST /session/1bab0f06-0616-43bf-b570-ffd308097624/frame {"id": null}
[task 2022-08-04T18:20:20.621Z] 18:20:20 INFO - PID 6380 | 1659637220619 Marionette DEBUG 0 -> [0,17,"WebDriver:SwitchToFrame",{"id":null}]
[task 2022-08-04T18:20:20.624Z] 18:20:20 INFO - PID 6380 | 1659637220628 Marionette TRACE [3] MarionetteCommands actor created for window id 4294967297
[task 2022-08-04T18:20:20.635Z] 18:20:20 INFO - PID 6380 | 1659637220638 Marionette DEBUG 0 <- [1,17,null,{"value":null}]
[task 2022-08-04T18:20:20.639Z] 18:20:20 INFO - PID 6380 | 1659637220638 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2022-08-04T18:20:20.640Z] 18:20:20 INFO - PID 6380 | 1659637220640 webdriver::server DEBUG -> DELETE /session/1bab0f06-0616-43bf-b570-ffd308097624
[task 2022-08-04T18:20:20.642Z] 18:20:20 INFO - PID 6380 | 1659637220641 Marionette DEBUG 0 -> [0,18,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2022-08-04T18:20:20.642Z] 18:20:20 INFO - PID 6380 | 1659637220642 Marionette INFO Stopped listening on port 60897
[task 2022-08-04T18:20:20.643Z] 18:20:20 INFO - PID 6380 | 1659637220643 WebDriver BiDi DEBUG Unregistered session handler: /session/1bab0f06-0616-43bf-b570-ffd308097624
[task 2022-08-04T18:20:20.645Z] 18:20:20 INFO - PID 6380 | 1659637220646 RemoteAgent TRACE MessageHandler ROOT for session 1bab0f06-0616-43bf-b570-ffd308097624 is being destroyed
[task 2022-08-04T18:20:20.648Z] 18:20:20 INFO - PID 6380 | 1659637220646 RemoteAgent TRACE Unregistered MessageHandler ROOT for session 1bab0f06-0616-43bf-b570-ffd308097624
[task 2022-08-04T18:20:20.648Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b60e8800 - BlockShutdown: NotifyImpendingShutdown.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3612
[task 2022-08-04T18:20:20.648Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216bb41aa00 - BlockShutdown: NotifyImpendingShutdown.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3612
[task 2022-08-04T18:20:20.651Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216bda26800 - BlockShutdown: NotifyImpendingShutdown.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3612
[task 2022-08-04T18:20:20.652Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216be5ef700 - BlockShutdown: NotifyImpendingShutdown.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3612
[task 2022-08-04T18:20:20.653Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b65b6900 - BlockShutdown: NotifyImpendingShutdown.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3612
[task 2022-08-04T18:20:20.720Z] 18:20:20 INFO - PID 6380 | 1659637220721 Marionette TRACE Received observer notification quit-application
[task 2022-08-04T18:20:20.731Z] 18:20:20 INFO - PID 6380 | 1659637220730 Marionette TRACE Received observer notification quit-application
[task 2022-08-04T18:20:20.731Z] 18:20:20 INFO - PID 6380 | 1659637220730 Marionette DEBUG Marionette stopped listening
[task 2022-08-04T18:20:20.733Z] 18:20:20 INFO - PID 6380 | 1659637220731 RemoteAgent TRACE Received observer notification quit-application
[task 2022-08-04T18:20:20.734Z] 18:20:20 INFO - PID 6380 | 1659637220733 Marionette DEBUG 0 <- [1,18,null,{"cause":"shutdown","forced":false}]
[task 2022-08-04T18:20:20.758Z] 18:20:20 INFO - PID 6380 | 1659637220767 RemoteAgent DEBUG Resetting recommended pref browser.contentblocking.features.standard
[task 2022-08-04T18:20:20.769Z] 18:20:20 INFO - PID 6380 | 1659637220767 RemoteAgent DEBUG Resetting recommended pref network.cookie.cookieBehavior
[task 2022-08-04T18:20:20.769Z] 18:20:20 INFO - PID 6380 | 1659637220767 webdriver::server DEBUG Teardown session
[task 2022-08-04T18:20:20.774Z] 18:20:20 INFO - PID 6380 | 1659637220773 Marionette DEBUG Closed connection 0
[task 2022-08-04T18:20:20.816Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b60e8800 - BlockShutdown: CanSend.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3657
[task 2022-08-04T18:20:20.816Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b60e8800 - ShutDownProcess: Sent shutdown message.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:1801
[task 2022-08-04T18:20:20.820Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216be5ef700 - BlockShutdown: CanSend.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3657
[task 2022-08-04T18:20:20.820Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216be5ef700 - ShutDownProcess: Sent shutdown message.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:1801
[task 2022-08-04T18:20:20.820Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b65b6900 - BlockShutdown: CanSend.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3657
[task 2022-08-04T18:20:20.820Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b65b6900 - ShutDownProcess: Sent shutdown message.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:1801
[task 2022-08-04T18:20:20.841Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216be5ef700 - ShutDownProcess: Closing channel.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:1841
[task 2022-08-04T18:20:20.856Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216be5ef700 - RemoveShutdownBlockers: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3772
[task 2022-08-04T18:20:20.857Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b60e8800 - ShutDownProcess: Closing channel.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:1841
[task 2022-08-04T18:20:20.857Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b60e8800 - RemoveShutdownBlockers: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3772
[task 2022-08-04T18:20:20.857Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b65b6900 - ShutDownProcess: Closing channel.: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:1841
[task 2022-08-04T18:20:20.857Z] 18:20:20 INFO - PID 6380 | [Parent 8664, Main Thread] WARNING: ContentParent: id=216b65b6900 - RemoveShutdownBlockers: file Z:/task_165962856663781/build/src/dom/ipc/ContentParent.cpp:3772
[task 2022-08-04T18:20:21.738Z] 18:20:21 INFO - PID 6380 | JavaScript error: resource://gre/modules/TerminatorTelemetry.jsm, line 60: AbortError: IOUtils: Shutting down and refusing additional I/O tasks
[task 2022-08-04T18:20:23.400Z] 18:20:23 INFO - PID 6380 | Waiting in WillDestroyCurrentMessageLoop for pid 5692
[task 2022-08-04T18:20:36.361Z] 18:20:36 INFO - TEST-UNEXPECTED-TIMEOUT | /_mozilla/webdriver/bidi/browsing_context/navigate/error.py | expected OK
[task 2022-08-04T18:20:36.361Z] 18:20:36 INFO - TEST-INFO took 55007ms
[task 2022-08-04T18:20:36.365Z] 18:20:36 INFO - Restarting browser for new test group
[task 2022-08-04T18:20:36.470Z] 18:20:36 INFO - Closing logging queue
[task 2022-08-04T18:20:36.476Z] 18:20:36 INFO - queue closed
[task 2022-08-04T18:20:36.987Z] 18:20:36 INFO - PID 4384 | 1659637236496 geckodriver INFO Listening on 127.0.0.1:49873
[task 2022-08-04T18:20:36.990Z] 18:20:36 INFO - Starting runner
[task 2022-08-04T18:20:37.626Z] 18:20:37 INFO - TEST-START | /_mozilla/webdriver/cdp/port_file.py
|
||
Updated•2 years ago
|
Summary: Intermittent TEST-UNEXPECTED-TIMEOUT | mozilla/tests/webdriver/bidi/browsing_context/navigate/error.py | expected OK → Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | mozilla/tests/webdriver/bidi/browsing_context/navigate/error.py | expected OK
|
|
||
Comment 1•2 years ago
•
|
||
First occurrence so far in this backfill range and retriggers: https://treeherder.mozilla.org/jobs?repo=mozilla-central&searchStr=Windows%2C10%2Cx64%2C2004%2CCCov%2CWebRender%2Copt%2CHeadless%2Cweb%2Cplatform%2Ctests%2Ctest-windows10-64-2004-ccov-qr%2Fopt-web-platform-tests-wdspec-headless%2CWd2&group_state=expanded&tochange=92717aa5366c725a043b71705ad2a1be3a83f413&fromchange=4cf66fe9deb65f1add1e82fdf518a5a1f7729984&selectedTaskRun=WseEwgyoRsOhjiGuLWURkQ.0
Before this merge, this was failing as a second or third line of failure.
Whiteboard: [retriggered]
| Comment hidden (Intermittent Failures Robot) |
|
||
Updated•2 years ago
|
Status: NEW → RESOLVED
Closed: 2 years ago
Component: web-platform-tests → geckodriver
Resolution: --- → DUPLICATE
|
|
||
Comment 4•2 years ago
|
||
Could we please re-classify these failures for bug 1783217? Thanks.
Flags: needinfo?(sheriffs)
|
||
Comment 6•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
•