Closed Bug 1490025 Opened 7 years ago Closed 6 years ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py TestAccessibility.test_click_raises_element_not_accessible | IOError: Process killed because the connection to Marionette server is lost.

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Product:
Testing
Component:
Marionette Client and Harness
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ccoroiu [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=198381711&repo=mozilla-central https://queue.taskcluster.net/v1/task/QL-Lm8uXS7Wi6yux2B6kUw/runs/0/artifacts/public/logs/live_backing.log task 2018-09-10T10:24:18.565Z] 10:24:18 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py TestAccessibility.test_click_raises_element_not_accessible [task 2018-09-10T10:24:18.569Z] 10:24:18 INFO - 1536575058567 Marionette DEBUG Closed connection 102 [task 2018-09-10T10:24:18.572Z] 10:24:18 INFO - 1536575058569 Marionette DEBUG Accepted connection 103 from 127.0.0.1:48076 [task 2018-09-10T10:24:18.574Z] 10:24:18 INFO - 1536575058573 Marionette TRACE 103 -> [0,1,"WebDriver:NewSession",{}] [task 2018-09-10T10:24:18.582Z] 10:24:18 INFO - 1536575058580 Marionette DEBUG [4294967297] Frame script loaded [task 2018-09-10T10:24:18.587Z] 10:24:18 INFO - 1536575058584 Marionette DEBUG [4294967297] Frame script registered [task 2018-09-10T10:24:18.594Z] 10:24:18 INFO - 1536575058588 Marionette TRACE 103 <- [1,1,null,{"sessionId":"602542df-3194-4521-9bdd-a8041c0b6e40","capabilities":{"browserName":"firefox","browserVersion":"64.0a ... ssID":1266,"moz:profile":"/tmp/tmp_5vLhq.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] [task 2018-09-10T10:24:18.596Z] 10:24:18 INFO - 1536575058590 Marionette TRACE 103 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}] [task 2018-09-10T10:24:18.596Z] 10:24:18 INFO - 1536575058591 Marionette TRACE 103 <- [1,2,null,{"value":null}] [task 2018-09-10T10:24:18.597Z] 10:24:18 INFO - 1536575058593 Marionette TRACE 103 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}] [task 2018-09-10T10:24:18.599Z] 10:24:18 INFO - 1536575058594 Marionette TRACE 103 <- [1,3,null,{"value":null}] [task 2018-09-10T10:24:18.603Z] 10:24:18 INFO - 1536575058598 Marionette TRACE 103 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}] [task 2018-09-10T10:24:18.604Z] 10:24:18 INFO - 1536575058600 Marionette TRACE 103 <- [1,4,null,{"value":null}] [task 2018-09-10T10:24:18.605Z] 10:24:18 INFO - 1536575058601 Marionette TRACE 103 -> [0,5,"Marionette:GetContext",{}] [task 2018-09-10T10:24:18.607Z] 10:24:18 INFO - 1536575058602 Marionette TRACE 103 <- [1,5,null,{"value":"content"}] [task 2018-09-10T10:24:18.608Z] 10:24:18 INFO - 1536575058605 Marionette TRACE 103 -> [0,6,"Marionette:SetContext",{"value":"chrome"}] [task 2018-09-10T10:24:18.609Z] 10:24:18 INFO - 1536575058606 Marionette TRACE 103 <- [1,6,null,{"value":null}] [task 2018-09-10T10:24:18.612Z] 10:24:18 INFO - 1536575058610 Marionette TRACE 103 -> [0,7,"Marionette:GetContext",{}] [task 2018-09-10T10:24:18.613Z] 10:24:18 INFO - 1536575058611 Marionette TRACE 103 <- [1,7,null,{"value":"chrome"}] [task 2018-09-10T10:24:18.615Z] 10:24:18 INFO - 1536575058613 Marionette TRACE 103 -> [0,8,"Marionette:SetContext",{"value":"chrome"}] [task 2018-09-10T10:24:18.616Z] 10:24:18 INFO - 1536575058614 Marionette TRACE 103 <- [1,8,null,{"value":null}] [task 2018-09-10T10:24:18.631Z] 10:24:18 INFO - 1536575058623 Marionette TRACE 103 -> [0,9,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["dom.ipc.processCount",1,false],"filename":".. ... = new Preferences({defaultBranch: defaultBranch});\n prefs.set(pref, value);","sandbox":"default","line":927}] [task 2018-09-10T10:24:18.643Z] 10:24:18 INFO - 1536575058637 Marionette TRACE 103 <- [1,9,null,{"value":null}] [task 2018-09-10T10:24:18.645Z] 10:24:18 INFO - 1536575058640 Marionette TRACE 103 -> [0,10,"Marionette:SetContext",{"value":"chrome"}] [task 2018-09-10T10:24:18.648Z] 10:24:18 INFO - 1536575058641 Marionette TRACE 103 <- [1,10,null,{"value":null}] [task 2018-09-10T10:24:18.649Z] 10:24:18 INFO - 1536575058643 Marionette TRACE 103 -> [0,11,"Marionette:SetContext",{"value":"content"}] [task 2018-09-10T10:24:18.650Z] 10:24:18 INFO - 1536575058645 Marionette TRACE 103 <- [1,11,null,{"value":null}] [task 2018-09-10T10:24:18.654Z] 10:24:18 INFO - 1536575058648 Marionette TRACE 103 -> [0,12,"WebDriver:DeleteSession",{}] [task 2018-09-10T10:24:18.659Z] 10:24:18 INFO - 1536575058656 Marionette TRACE 103 <- [1,12,null,{"value":null}] [task 2018-09-10T10:24:18.668Z] 10:24:18 INFO - 1536575058660 Marionette DEBUG Closed connection 103 [task 2018-09-10T10:24:18.669Z] 10:24:18 INFO - 1536575058662 Marionette DEBUG Accepted connection 104 from 127.0.0.1:48078 [task 2018-09-10T10:24:18.670Z] 10:24:18 INFO - 1536575058664 Marionette TRACE 104 -> [0,1,"WebDriver:NewSession",{"moz:accessibilityChecks":true,"capabilities":{"moz:accessibilityChecks":true}}] [task 2018-09-10T10:32:19.892Z] 10:32:19 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py TestAccessibility.test_click_raises_element_not_accessible | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s) [task 2018-09-10T10:32:19.892Z] 10:32:19 INFO - Traceback (most recent call last): [task 2018-09-10T10:32:19.893Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run [task 2018-09-10T10:32:19.893Z] 10:32:19 INFO - testMethod() [task 2018-09-10T10:32:19.893Z] 10:32:19 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py", line 152, in test_click_raises_element_not_accessible [task 2018-09-10T10:32:19.894Z] 10:32:19 INFO - self.setup_accessibility() [task 2018-09-10T10:32:19.894Z] 10:32:19 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py", line 113, in setup_accessibility [task 2018-09-10T10:32:19.895Z] 10:32:19 INFO - self.marionette.start_session({"moz:accessibilityChecks": enable_a11y_checks}) [task 2018-09-10T10:32:19.895Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _ [task 2018-09-10T10:32:19.896Z] 10:32:19 INFO - return func(*args, **kwargs) [task 2018-09-10T10:32:19.896Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1257, in start_session [task 2018-09-10T10:32:19.897Z] 10:32:19 INFO - body) [task 2018-09-10T10:32:19.897Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 31, in _ [task 2018-09-10T10:32:19.898Z] 10:32:19 INFO - m._handle_socket_failure() [task 2018-09-10T10:32:19.898Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 839, in _handle_socket_failure [task 2018-09-10T10:32:19.899Z] 10:32:19 INFO - reraise(IOError, message.format(returncode=returncode, reason=val), tb) [task 2018-09-10T10:32:19.900Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _ [task 2018-09-10T10:32:19.901Z] 10:32:19 INFO - return func(*args, **kwargs) [task 2018-09-10T10:32:19.902Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 751, in _send_message [task 2018-09-10T10:32:19.902Z] 10:32:19 INFO - msg = self.client.request(name, params) [task 2018-09-10T10:32:19.903Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 273, in request [task 2018-09-10T10:32:19.903Z] 10:32:19 INFO - return self.receive() [task 2018-09-10T10:32:19.904Z] 10:32:19 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 183, in receive [task 2018-09-10T10:32:19.904Z] 10:32:19 INFO - raise socket.timeout("Connection timed out after {}s".format(self.socket_timeout)) [task 2018-09-10T10:32:19.905Z] 10:32:19 INFO - TEST-INFO took 481324ms [task 2018-09-10T10:32:19.906Z] 10:32:19 ERROR - test_end for testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py TestAccessibility.test_click_raises_element_not_accessible logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_accessibility.TestAccessibility", "method_name": "test_click_raises_element_not_accessible"}, "expected": "PASS", "test": "testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py TestAccessibility.test_click_raises_element_not_accessible", "message": "InvalidSessionIdException: Please start a session", "stack": "Traceback (most recent call last):\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 190, in run\n self.tearDown()\n File \"/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py\", line 28, in tearDown\n with self.marionette.using_context(\"chrome\"):\n File \"/usr/lib/python2.7/contextlib.py\", line 17, in __enter__\n return self.gen.next()\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 1450, in using_context\n key=\"value\")\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py\", line 26, in _\n return func(*args, **kwargs)\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 748, in _send_message\n raise errors.InvalidSessionIdException(\"Please start a session\")\n"}
This is a hang in the `Webdriver:NewSession` command: > [task 2018-09-10T10:24:18.670Z] 10:24:18 INFO - 1536575058664 Marionette TRACE 104 -> [0,1,"WebDriver:NewSession",{"moz:accessibilityChecks":true,"capabilities":{"moz:accessibilityChecks":true}}] > [task 2018-09-10T10:32:19.892Z] 10:32:19 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_accessibility.py TestAccessibility.test_click_raises_element_not_accessible | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Product: Testing → Remote Protocol
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.