Closed
Bug 1721732
Opened 4 years ago
Closed 4 years ago
Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_cli_arguments.py TestCommandLineArguments.test_debugger_address_cdp_status | OSError: Process killed because the connection to Marionette server is lost. Check gecko.log for e
Categories
(Testing :: Marionette Client and Harness, defect, P5)
Testing
Marionette Client and Harness
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=345928014&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NzujKEJ9Sfe_RYaPY7Rnfg/runs/0/artifacts/public/logs/live_backing.log
[task 2021-07-21T19:58:23.836Z] 19:58:23 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_cli_arguments.py TestCommandLineArguments.test_debugger_address_cdp_status
[task 2021-07-21T19:58:23.837Z] 19:58:23 INFO - 1626897503837 Marionette DEBUG Accepted connection 12 from 127.0.0.1:49978
[task 2021-07-21T19:58:23.838Z] 19:58:23 INFO - 1626897503837 Marionette DEBUG 12 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-07-21T19:58:23.839Z] 19:58:23 INFO - 1626897503838 Marionette DEBUG 12 <- [1,1,null,{"sessionId":"e8983da6-95b1-48c3-9efe-015adac42788","capabilities":{"browserName":"firefox","browserVersion":"92.0a ... e.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-07-21T19:58:23.839Z] 19:58:23 INFO - 1626897503839 Marionette DEBUG 12 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2021-07-21T19:58:23.839Z] 19:58:23 INFO - 1626897503839 Marionette DEBUG 12 <- [1,2,null,{"value":null}]
[task 2021-07-21T19:58:23.840Z] 19:58:23 INFO - 1626897503839 Marionette DEBUG 12 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2021-07-21T19:58:23.840Z] 19:58:23 INFO - 1626897503840 Marionette DEBUG 12 <- [1,3,null,{"value":null}]
[task 2021-07-21T19:58:23.840Z] 19:58:23 INFO - 1626897503840 Marionette DEBUG 12 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2021-07-21T19:58:23.841Z] 19:58:23 INFO - 1626897503840 Marionette DEBUG 12 <- [1,4,null,{"value":null}]
[task 2021-07-21T19:58:23.842Z] 19:58:23 INFO - 1626897503841 Marionette DEBUG 12 -> [0,5,"Marionette:GetContext",{}]
[task 2021-07-21T19:58:23.842Z] 19:58:23 INFO - 1626897503841 Marionette DEBUG 12 <- [1,5,null,{"value":"content"}]
[task 2021-07-21T19:58:23.842Z] 19:58:23 INFO - 1626897503842 Marionette DEBUG 12 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-07-21T19:58:23.843Z] 19:58:23 INFO - 1626897503842 Marionette DEBUG 12 <- [1,6,null,{"value":null}]
[task 2021-07-21T19:58:23.844Z] 19:58:23 INFO - 1626897503843 Marionette DEBUG 12 -> [0,7,"WebDriver:ExecuteScript",{"script":"let prefInterface = Components.classes[\"@mozilla.org/preferences-service;1\"]\n ... ault","line":896,"filename":"C:\\Users\\task_1626888632\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2021-07-21T19:58:23.844Z] 19:58:23 INFO - 1626897503844 Marionette TRACE [13] MarionetteCommands actor created for window id 2
[task 2021-07-21T19:58:23.845Z] 19:58:23 INFO - 1626897503845 Marionette DEBUG 12 <- [1,7,null,{"value":false}]
[task 2021-07-21T19:58:23.846Z] 19:58:23 INFO - 1626897503846 Marionette DEBUG 12 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2021-07-21T19:58:23.846Z] 19:58:23 INFO - 1626897503846 Marionette DEBUG 12 <- [1,8,null,{"value":null}]
[task 2021-07-21T19:58:23.847Z] 19:58:23 INFO - 1626897503846 Marionette DEBUG 12 -> [0,9,"Marionette:GetContext",{}]
[task 2021-07-21T19:58:23.847Z] 19:58:23 INFO - 1626897503846 Marionette DEBUG 12 <- [1,9,null,{"value":"content"}]
[task 2021-07-21T19:58:23.847Z] 19:58:23 INFO - 1626897503847 Marionette DEBUG 12 -> [0,10,"WebDriver:DeleteSession",{}]
[task 2021-07-21T19:58:23.847Z] 19:58:23 INFO - 1626897503847 Marionette DEBUG 12 <- [1,10,null,{"value":null}]
[task 2021-07-21T19:58:23.848Z] 19:58:23 INFO - 1626897503848 Marionette DEBUG Closed connection 12
[task 2021-07-21T19:58:23.979Z] 19:58:23 INFO - Application command: C:\Users\task_1626888632\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile C:\Users\task_1626888632\AppData\Local\Temp\tmpxr8lbc35.mozrunner
[task 2021-07-21T19:58:24.135Z] 19:58:24 INFO - DEBUG: Adding blocker OS.File: flush pending requests, warn about unclosed files, shut down service. for phase xpcom-will-shutdown
[task 2021-07-21T19:58:24.135Z] 19:58:24 INFO - DEBUG: Adding blocker OS.File: flush I/O queued before profileBeforeChange for phase profile-before-change
[task 2021-07-21T19:58:24.136Z] 19:58:24 INFO - DEBUG: Adding blocker AddonManager: shutting down. for phase profile-before-change
[task 2021-07-21T19:58:24.152Z] 19:58:24 INFO - DEBUG: Adding blocker IOUtils Blocker (profile-before-change) for phase profile-before-change
[task 2021-07-21T19:58:24.153Z] 19:58:24 INFO - DEBUG: Adding blocker IOUtils Blocker (xpcom-will-shutdown) for phase xpcom-will-shutdown
[task 2021-07-21T19:58:24.183Z] 19:58:24 INFO - DEBUG: Adding blocker Flush WebExtension StartupCache for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2021-07-21T19:58:24.239Z] 19:58:24 INFO - DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2021-07-21T19:58:24.300Z] 19:58:24 INFO - DEBUG: Adding blocker JSON store: writing data for phase AddonManager: Waiting for providers to shut down.
[task 2021-07-21T19:58:24.302Z] 19:58:24 INFO - DEBUG: Adding blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2021-07-21T19:58:24.303Z] 19:58:24 INFO - DEBUG: Completed blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2021-07-21T19:58:24.308Z] 19:58:24 INFO - DEBUG: Adding blocker XPIProvider shutdown for phase quit-application-granted
[task 2021-07-21T19:58:24.310Z] 19:58:24 INFO - DEBUG: Adding blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2021-07-21T19:58:24.311Z] 19:58:24 INFO - DEBUG: Adding blocker ServiceWorkerRegistrar: Flushing data for phase profile-before-change
[task 2021-07-21T19:58:24.312Z] 19:58:24 INFO - DEBUG: Adding blocker CrashMonitor: Writing notifications to file after receiving profile-before-change for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2021-07-21T19:58:24.326Z] 19:58:24 INFO - DEBUG: Adding blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2021-07-21T19:58:24.354Z] 19:58:24 INFO - DEBUG: Adding blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2021-07-21T19:58:24.363Z] 19:58:24 INFO - DEBUG: Adding blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2021-07-21T19:58:24.368Z] 19:58:24 INFO - 1626897504368 Marionette INFO Marionette enabled
[task 2021-07-21T19:58:24.442Z] 19:58:24 INFO - 1626897504442 RemoteAgent DEBUG WebDriver BiDi enabled
[task 2021-07-21T20:01:35.139Z] 20:01:35 ERROR - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_cli_arguments.py TestCommandLineArguments.test_debugger_address_cdp_status | OSError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on 127.0.0.1:2828!)
[task 2021-07-21T20:01:35.140Z] 20:01:35 INFO - Traceback (most recent call last):
[task 2021-07-21T20:01:35.140Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 202, in run
[task 2021-07-21T20:01:35.141Z] 20:01:35 INFO - testMethod()
[task 2021-07-21T20:01:35.141Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_cli_arguments.py", line 34, in test_debugger_address_cdp_status
[task 2021-07-21T20:01:35.141Z] 20:01:35 INFO - self.marionette.enforce_gecko_prefs({"remote.active-protocols": 1})
[task 2021-07-21T20:01:35.142Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2021-07-21T20:01:35.143Z] 20:01:35 INFO - m._handle_socket_failure()
[task 2021-07-21T20:01:35.143Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\venv\lib\site-packages\marionette_driver\marionette.py", line 717, in _handle_socket_failure
[task 2021-07-21T20:01:35.143Z] 20:01:35 INFO - IOError, IOError(message.format(returncode=returncode, reason=exc)), tb
[task 2021-07-21T20:01:35.144Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\venv\lib\site-packages\six.py", line 702, in reraise
[task 2021-07-21T20:01:35.144Z] 20:01:35 INFO - raise value.with_traceback(tb)
[task 2021-07-21T20:01:35.144Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2021-07-21T20:01:35.145Z] 20:01:35 INFO - return func(*args, **kwargs)
[task 2021-07-21T20:01:35.145Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\venv\lib\site-packages\marionette_driver\marionette.py", line 906, in enforce_gecko_prefs
[task 2021-07-21T20:01:35.145Z] 20:01:35 INFO - self.raise_for_port()
[task 2021-07-21T20:01:35.146Z] 20:01:35 INFO - File "C:\Users\task_1626888632\build\venv\lib\site-packages\marionette_driver\marionette.py", line 600, in raise_for_port
[task 2021-07-21T20:01:35.146Z] 20:01:35 INFO - self.host, self.port
[task 2021-07-21T20:01:35.146Z] 20:01:35 INFO - TEST-INFO took 191302ms
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
|
||
Updated•2 years ago
|
Product: Testing → Remote Protocol
|
|
||
Comment 4•2 years ago
|
||
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in
before you can comment on or make changes to this bug.
Description
•