1438791 - Intermittent jsreftest Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_1518695034\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1518695034\\build\\tests\\reftest\\runreftest.py', '--total-chunks', '2', '--this-chunk', '2',

Status: RESOLVED DUPLICATE of bug 1436237

(Core :: JavaScript Engine, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: archaeopteryx [at] coole-files.de

https://treeherder.mozilla.org/logviewer.html#?job_id=162391486&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/MbBPb8TQTxiW1cfhgJx3Vg/runs/0/artifacts/public/logs/live_backing.log

https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/MbBPb8TQTxiW1cfhgJx3Vg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

11:45:35     INFO -  'USERPROFILE': 'C:\\Users\\GenericWorker',
11:45:35     INFO -  'WINDIR': 'C:\\Windows'}
11:45:35     INFO - Calling ['Z:\\task_1518695034\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1518695034\\build\\tests\\reftest\\runreftest.py', '--total-chunks', '2', '--this-chunk', '2', '--appname=Z:\\task_1518695034\\build\\application\\firefox\\firefox.exe', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://queue.taskcluster.net/v1/task/HYHzYAF-SrSso83p5Gq__w/artifacts/public/build/target.crashreporter-symbols.zip', '--log-raw=Z:\\task_1518695034\\build\\blobber_upload_dir\\jsreftest_raw.log', '--log-errorsummary=Z:\\task_1518695034\\build\\blobber_upload_dir\\jsreftest_errorsummary.log', '--cleanup-crashes', '--marionette-startup-timeout=180', '--extra-profile-file=tests/jsreftest/tests/user.js', '--suite=jstestbrowser', '--', 'tests/jsreftest/tests/jstests.list'] with output_timeout 1000
11:45:35     INFO -  REFTEST INFO | Running with e10s: True
11:45:35     INFO -  REFTEST INFO | Application command: Z:\task_1518695034\build\application\firefox\firefox.exe -marionette -profile c:\users\genericworker\appdata\local\temp\tmpk3gzep.mozrunner
11:45:36     INFO -  1518695136466	Marionette	DEBUG	Received observer notification profile-after-change
11:45:36     INFO -  1518695136574	Marionette	DEBUG	Received observer notification command-line-startup
11:45:36     INFO -  1518695136574	Marionette	DEBUG	Received observer notification nsPref:changed
11:45:37     INFO -  1518695137666	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
11:45:37     INFO -  [DEBUG SHUTDOWN] Register: decoder=000001B687FA1400, count=1
11:45:38     INFO -  [DEBUG SHUTDOWN] ShutdownDecoder: decoder: 'wmf software video decoder - yuv420' (000001B687EB1860) flush:1
11:45:38     INFO -  [DEBUG SHUTDOWN] operator (): pool=000001B685CA3730 shutdown=false count=0
11:45:38     INFO -  1518695138128	Marionette	DEBUG	Received observer notification domwindowclosed
11:45:38     INFO -  1518695138260	Marionette	DEBUG	New connections are accepted
11:45:38     INFO -  1518695138260	Marionette	INFO	Listening on port 2828
11:45:38     INFO -  1518695138518	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:49770
11:45:38     INFO -  1518695138616	Marionette	DEBUG	Closed connection 0
11:45:38     INFO -  1518695138618	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:49773
11:45:38     INFO -  1518695138716	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:49774
11:45:38     INFO -  1518695138718	Marionette	DEBUG	Closed connection 2
11:45:38     INFO -  1518695138718	Marionette	DEBUG	Closed connection 1
11:45:38     INFO -  1518695138718	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:49775
11:45:38     INFO -  1518695138720	Marionette	TRACE	3 -> [0,1,"newSession",{}]
11:45:38     INFO -  1518695138780	Marionette	DEBUG	Register listener.js for window 4294967297
11:45:38     INFO -  1518695138786	Marionette	TRACE	3 <- [1,1,null,{"sessionId":"d7bfd140-f3c4-45b1-87c3-597070c86b7b","capabilities":{"browserName":"firefox","browserVersion":"60.0a ... cWorker\\AppData\\Local\\Temp\\tmpk3gzep.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
11:45:38     INFO -  1518695138790	Marionette	TRACE	3 -> [0,2,"addon:install",{"path":"Z:\\task_1518695034\\build\\tests\\reftest\\specialpowers","temporary":true}]
11:45:38     INFO -  1518695138812	Marionette	TRACE	3 <- [1,2,null,{"value":"[e-mail]"}]
11:45:38     INFO -  1518695138820	Marionette	TRACE	3 -> [0,3,"addon:install",{"path":"Z:\\task_1518695034\\build\\tests\\reftest\\reftest","temporary":true}]
11:45:38     INFO -  1518695138834	Marionette	TRACE	3 <- [1,3,null,{"value":"[e-mail]"}]
11:45:38     INFO -  1518695138922	Marionette	TRACE	3 -> [0,4,"deleteSession",{}]
11:45:38     INFO -  1518695138924	Marionette	TRACE	3 <- [1,4,null,{}]
11:45:38     INFO -  1518695138958	Marionette	DEBUG	Closed connection 3
11:45:38     INFO -  Unable to read VR Path Registry from C:\Users\GenericWorker\AppData\Local\openvr\openvrpaths.vrpath
11:45:38     INFO -  [Child 4016, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
11:45:38     INFO -  [Child 4016, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
11:45:39     INFO -  Unable to read VR Path Registry from C:\Users\GenericWorker\AppData\Local\openvr\openvrpaths.vrpath
11:45:39     INFO -  [Parent 8064, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
11:45:55     INFO -  [DEBUG SHUTDOWN] Shutdown: decoder=000001B687FA1400 state machine=000001B685CD1000
11:45:55     INFO -  [DEBUG SHUTDOWN] Enter: state machine=000001B6863082E0 reader=000001B6855AD8B0
11:45:55     INFO -  [DEBUG SHUTDOWN] Shutdown: reader=000001B685CCE000 shutdown demuxer=000001B687F3F820
11:45:55     INFO -  [DEBUG SHUTDOWN] Shutdown: pool=000001B685CA3730 count=1
11:45:55     INFO -  [DEBUG SHUTDOWN] operator (): pool=000001B685CA3730 shutdown=true count=0
11:45:55     INFO -  [DEBUG SHUTDOWN] TearDownDecoders: reader=000001B685CCE000 shut down audio task queue
11:45:55     INFO -  [DEBUG SHUTDOWN] TearDownDecoders: reader=000001B685CCE000 shut down video task queue
11:45:55     INFO -  [DEBUG SHUTDOWN] FinishShutdown: state machine=000001B685CD1000
11:45:55     INFO -  [DEBUG SHUTDOWN] Unregister: decoder=000001B687FA1400, count=0
12:02:35     INFO - Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_1518695034\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1518695034\\build\\tests\\reftest\\runreftest.py', '--total-chunks', '2', '--this-chunk', '2', '--appname=Z:\\task_1518695034\\build\\application\\firefox\\firefox.exe', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://queue.taskcluster.net/v1/task/HYHzYAF-SrSso83p5Gq__w/artifacts/public/build/target.crashreporter-symbols.zip', '--log-raw=Z:\\task_1518695034\\build\\blobber_upload_dir\\jsreftest_raw.log', '--log-errorsummary=Z:\\task_1518695034\\build\\blobber_upload_dir\\jsreftest_errorsummary.log', '--cleanup-crashes', '--marionette-startup-timeout=180', '--extra-profile-file=tests/jsreftest/tests/user.js', '--suite=jstestbrowser', '--', 'tests/jsreftest/tests/jstests.list']

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* windows10-64: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1438791&startday=2018-02-12&endday=2018-02-18&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 675 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* try: 1

Platform breakdown:
* windows10-64-devedition: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1438791&startday=2018-02-19&endday=2018-02-25&tree=all