1576752 - Intermittent Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_1566844562\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1566844562\\build\\tests\\marionette\\harness\\marionette_harness\\runtests.py'

Status: RESOLVED DUPLICATE of bug 1526880

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

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=263552295&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/Mr9NXAnwQdWHsnWwlcszhA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-08-26T19:34:13.190Z] 19:34:13 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_cli_arguments.py TestCommandLineArguments.test_start_in_safe_mode
[task 2019-08-26T19:34:13.193Z] 19:34:13 INFO - 1566848053190 Marionette DEBUG Accepted connection 12 from 127.0.0.1:49433
[task 2019-08-26T19:34:13.193Z] 19:34:13 INFO - 1566848053191 Marionette DEBUG 12 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-08-26T19:34:13.196Z] 19:34:13 INFO - 1566848053194 Marionette TRACE [5] Frame script loaded
[task 2019-08-26T19:34:13.196Z] 19:34:13 INFO - 1566848053195 Marionette TRACE [5] Frame script registered
[task 2019-08-26T19:34:13.203Z] 19:34:13 INFO - 1566848053196 Marionette DEBUG 12 <- [1,1,null,{"sessionId":"6e642d2d-dc25-4dc6-ac9e-753d4d387750","capabilities":{"browserName":"firefox","browserVersion":"70.0a ... p\tmpnmtyrj.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-08-26T19:34:13.203Z] 19:34:13 INFO - 1566848053199 Marionette DEBUG 12 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-08-26T19:34:13.203Z] 19:34:13 INFO - 1566848053199 Marionette DEBUG 12 <- [1,2,null,{"value":null}]
[task 2019-08-26T19:34:13.203Z] 19:34:13 INFO - 1566848053201 Marionette DEBUG 12 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-08-26T19:34:13.203Z] 19:34:13 INFO - 1566848053201 Marionette DEBUG 12 <- [1,3,null,{"value":null}]
[task 2019-08-26T19:34:13.206Z] 19:34:13 INFO - 1566848053202 Marionette DEBUG 12 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-08-26T19:34:13.206Z] 19:34:13 INFO - 1566848053203 Marionette DEBUG 12 <- [1,4,null,{"value":null}]
[task 2019-08-26T19:34:13.221Z] 19:34:13 INFO - Exiting due to channel error.

[task 2019-08-26T19:34:18.839Z] 19:34:18 INFO - Exiting due to channel error.
[task 2019-08-26T19:34:18.839Z] 19:34:18 INFO - Exiting due to channel error.
[task 2019-08-26T19:50:58.845Z] 19:50:58 INFO - Automation Error: mozprocess timed out after 1000 seconds running ['Z:\task_1566844562\build\venv\Scripts\python', '-u', 'Z:\task_1566844562\build\tests\marionette\harness\marionette_harness\runtests.py', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--gecko-log=-', '-vv', '--log-raw=Z:\task_1566844562\build\blobber_upload_dir\marionette_raw.log', '--log-errorsummary=Z:\task_1566844562\build\blobber_upload_dir\marionette_errorsummary.log', '--log-html=Z:\task_1566844562\build\blobber_upload_dir\report.html', '--binary=Z:\task_1566844562\build\application\firefox\firefox.exe', '--address=localhost:2828', '--symbols-path=Z:\task_1566844562\build\symbols', 'Z:\task_1566844562\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit-tests.ini']
[task 2019-08-26T19:50:58.858Z] 19:50:58 ERROR - timed out after 1000 seconds of no output
[task 2019-08-26T19:50:58.858Z] 19:50:58 ERROR - Return code: 572
[task 2019-08-26T19:50:58.858Z] 19:50:58 INFO - TinderboxPrint: marionette<br/><em class="testfail">T-FAIL</em>
[task 2019-08-26T19:50:58.858Z] 19:50:58 INFO - gecko.log not found
[task 2019-08-26T19:50:58.858Z] 19:50:58 INFO - TinderboxPrint: marionette<br/><em class="testfail">T-FAIL</em>
[task 2019-08-26T19:50:58.858Z] 19:50:58 INFO - Marionette exited with return code 572: FAILURE
[task 2019-08-26T19:50:58.858Z] 19:50:58 ERROR - # TBPL FAILURE #
[task 2019-08-26T19:50:58.858Z] 19:50:58 WARNING - setting return code to 2

Comment 2

[Intermittent Failures Robot]

2 failures in 5047 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows7-32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1576752&startday=2019-08-26&endday=2019-09-01&tree=all

Comment 3

[BMO Automation]

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.