Closed Bug 1759641 Opened 3 years ago Closed 3 years ago

Intermittent Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_164731577540695\\build\\venv\\Scripts\\python', '-u', '*/pageinfo/browser.ini', '*/popupNotifications/browser.ini', '*/tabMediaIndicator/browser.ini'

Categories

(Testing :: Mochitest, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1545630

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=371055013&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Da1d6juVTaWPpmGEdkljPQ/runs/0/artifacts/public/logs/live_backing.log


[task 2022-03-15T04:05:15.859Z] 04:05:15     INFO - TEST-START | gfx/layers/apz/test/mochitest/browser_test_group_fission.js
[task 2022-03-15T04:05:16.715Z] 04:05:16     INFO - GECKO(6336) | Starting test http://mochi.test:8888/browser/gfx/layers/apz/test/mochitest/helper_fission_basic.html
[task 2022-03-15T04:05:17.132Z] 04:05:17     INFO - GECKO(6336) | WaitUntilApzStable: flushed APZ repaints in parent proc, waiting for callback...
[task 2022-03-15T04:05:17.146Z] 04:05:17     INFO - GECKO(6336) | WaitUntilApzStable: APZ flush done in parent proc
[task 2022-03-15T04:05:17.150Z] 04:05:17     INFO - GECKO(6336) | WaitUntilApzStable: got apz-flush-done in child proc
<...>
[task 2022-03-15T04:05:18.959Z] 04:05:18     INFO - GECKO(6336) | Starting test http://mochi.test:8888/browser/gfx/layers/apz/test/mochitest/helper_fission_scroll_oopif.html
[task 2022-03-15T04:05:19.384Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: flushed APZ repaints in parent proc, waiting for callback...
[task 2022-03-15T04:05:19.384Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: APZ flush done in parent proc
[task 2022-03-15T04:05:19.399Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: got apz-flush-done in child proc
[task 2022-03-15T04:05:19.401Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: done promiseFocus
[task 2022-03-15T04:05:19.430Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: done promiseAllPaintsDone
[task 2022-03-15T04:05:19.435Z] 04:05:19     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-03-15T04:05:19.447Z] 04:05:19     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-03-15T04:05:19.450Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: all done
[task 2022-03-15T04:05:19.685Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: flushed APZ repaints in parent proc, waiting for callback...
[task 2022-03-15T04:05:19.700Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: APZ flush done in parent proc
[task 2022-03-15T04:05:19.701Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: got apz-flush-done in child proc
[task 2022-03-15T04:05:19.703Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: done promiseFocus
[task 2022-03-15T04:05:19.704Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: done promiseAllPaintsDone
[task 2022-03-15T04:05:19.706Z] 04:05:19     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-03-15T04:05:19.718Z] 04:05:19     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-03-15T04:05:19.721Z] 04:05:19     INFO - GECKO(6336) | WaitUntilApzStable: all done
[task 2022-03-15T04:05:19.723Z] 04:05:19     INFO - GECKO(6336) | OOPIF registered click listener
[task 2022-03-15T04:05:19.731Z] 04:05:19     INFO - GECKO(6336) | OOPIF response: true
[task 2022-03-15T04:05:19.776Z] 04:05:19     INFO - GECKO(6336) | Finished synthesizing click, waiting for OOPIF message...
[task 2022-03-15T04:05:19.788Z] 04:05:19     INFO - GECKO(6336) | OOPIF got click at 48,48
[task 2022-03-15T04:05:19.792Z] 04:05:19     INFO - GECKO(6336) | OOPIF response: {"x":48,"y":48}
[task 2022-03-15T04:05:20.086Z] 04:05:20     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-03-15T04:05:20.124Z] 04:05:20     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-03-15T04:05:20.155Z] 04:05:20     INFO - GECKO(6336) | Finished synthesizing click, waiting for OOPIF message...
[task 2022-03-15T04:05:20.173Z] 04:05:20     INFO - GECKO(6336) | OOPIF got click at 48,48
[task 2022-03-15T04:05:20.174Z] 04:05:20     INFO - GECKO(6336) | OOPIF response: {"x":48,"y":48}
[task 2022-03-15T04:05:20.177Z] 04:05:20     INFO - GECKO(6336) | OOPIF scroll position is y=0
[task 2022-03-15T04:05:20.179Z] 04:05:20     INFO - GECKO(6336) | OOPIF response for scrollPos: {"y":0}
[task 2022-03-15T04:05:20.208Z] 04:05:20     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-03-15T04:05:20.237Z] 04:05:20     INFO - GECKO(6336) | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-03-15T04:10:50.528Z] 04:10:50     INFO - GECKO(6336) | 1647317450536	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates
[task 2022-03-15T04:27:30.762Z] 04:27:30     INFO - Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_164731577540695\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_164731577540695\\build\\tests\\mochitest\\runtests.py', 'browser/base/content/test/pageinfo/browser.ini', 'browser/base/content/test/popupNotifications/browser.ini', 'browser/base/content/test/tabMediaIndicator/browser.ini', 'browser/components/attribution/test/browser/browser.ini', 'browser/components/enterprisepolicies/tests/browser/disable_forget_button/browser.ini', 'browser/components/enterprisepolicies/tests/browser/disable_fxscreenshots/browser.ini', 'browser/components/places/tests/browser/interactions/browser.ini', 'browser/components/shell/test/browser.ini', 'browser/components/tests/browser/browser.ini', 'browser/themes/test/browser/browser.ini', 'dom/events/test/browser.ini', 'dom/security/test/csp/browser.ini', 'dom/security/test/https-only/browser.ini', 'dom/security/test/sec-fetch/browser.ini', 'dom/tests/browser/perfmetrics/browser.ini', 'editor/libeditor/tests/browser.ini', 'gfx/layers/apz/test/mochitest/browser.ini', 'toolkit/components/antitracking/test/browser/browser-blocking.ini', 'toolkit/components/tooltiptext/tests/browser.ini', 'widget/tests/browser/browser.ini', '--setpref=fission.autostart=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--appname=Z:\\task_164731577540695\\build\\application\\firefox\\firefox.exe', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ToAHjeLXQ9qT0RTlgRmNuQ/artifacts/public/build/target.crashreporter-symbols.zip', '--certificate-path=tests/certs', '--quiet', '--log-raw=Z:\\task_164731577540695\\build\\blobber_upload_dir\\mochitest-browser-chrome_raw.log', '--log-errorsummary=Z:\\task_164731577540695\\build\\blobber_upload_dir\\mochitest-browser-chrome_errorsummary.log', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--log-raw=-', '--flavor=browser', '--chunk-by-runtime']
[task 2022-03-15T04:27:30.766Z] 04:27:30    ERROR - timed out after 1000 seconds of no output
[task 2022-03-15T04:27:30.766Z] 04:27:30    ERROR - Return code: 572
[task 2022-03-15T04:27:30.766Z] 04:27:30    ERROR - No suite end message was emitted by this harness.
[task 2022-03-15T04:27:30.766Z] 04:27:30     INFO - TinderboxPrint: mochitest-mochitest-browser-chrome<br/>112/0/0
[task 2022-03-15T04:27:30.766Z] 04:27:30    ERROR - # TBPL FAILURE #
[task 2022-03-15T04:27:30.766Z] 04:27:30  WARNING - setting return code to 2
[task 2022-03-15T04:27:30.766Z] 04:27:30    ERROR - The mochitest suite: mochitest-browser-chrome ran with return status: FAILURE
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.