Intermittent test_cli_arguments.py TestCommandLineArguments.test_startup_timeout causes hangs after Firefox restart on Windows

RESOLVED FIXED in Firefox 65

Status

defect
P5
normal
RESOLVED FIXED
2 years ago
10 months ago

People

(Reporter: whimboo, Assigned: whimboo)

Tracking

(Depends on 1 bug, Blocks 1 bug, {intermittent-failure})

Trunk
mozilla65
Points:
---
Dependency tree / graph

Firefox Tracking Flags

(firefox61 disabled, firefox63 disabled, firefox64 disabled, firefox65 fixed)

Details

Attachments

(2 attachments)

As noticed on bug 1391545 comment 67 we have a lot of 1000s timeouts on Windows for the above test.

https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-beta&job_id=154792849&lineNumber=1272

16:50:40     INFO -  TEST-START: test_cli_arguments.py TestCommandLineArguments.test_startup_timeout
16:50:40     INFO -  1515430240619	Marionette	TRACE	3 <- [1,5,null,{"value":null}]
16:50:40     INFO -  1515430240620	Marionette	TRACE	3 -> [0,6,"deleteSession",{}]
16:50:40     INFO -  1515430240621	Marionette	TRACE	3 <- [1,6,null,{}]
16:50:40     INFO -  [GF[GFX1-]: Receive IPC close wit[GX1-h reason]: ReF=ceive IPC close wiX1-]Ath rbnormalShutdown
16:50:40     INFO -  eason=AbnormalShutdown
16:50:40     INFO -  : Receive IPC close with reason=AbnormalShutdown
16:50:40     INFO -  Application command: Z:\task_1515430051\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\genericworker\appdata\local\temp\tmpvrnrxb.mozrunner
16:50:40     INFO -  1515430240923	Marionette	DEBUG	Received observer notification "profile-after-change"
16:50:40     INFO -  1515430240964	Marionette	DEBUG	Received observer notification "command-line-startup"
16:50:40     INFO -  1515430240964	Marionette	INFO	Enabled via --marionette
17:07:20     INFO - Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_1515430051\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1515430051\\build\\tests\\marionette\\harness\\marionette_harness\\runtests.py', '--gecko-log=-', '-vv', '--log-raw=Z:\\task_1515430051\\build\\blobber_upload_dir\\marionette_raw.log', '--log-errorsummary=Z:\\task_1515430051\\build\\blobber_upload_dir\\marionette_errorsummary.log', '--log-html=Z:\\task_1515430051\\build\\blobber_upload_dir\\report.html', '--binary=Z:\\task_1515430051\\build\\application\\firefox\\firefox.exe', '--address=localhost:2828', '--symbols-path=https://queue.taskcluster.net/v1/task/ai_xIll0QU2i9Kd8WDhxCw/artifacts/public/build/target.crashreporter-symbols.zip', 'Z:\\task_1515430051\\build\\tests\\marionette\\tests\\testing\\marionette\\harness\\marionette_harness\\tests\\unit-tests.ini']
17:07:21    ERROR - timed out after 1000 seconds of no output

In those cases we do not get a sessionstore-windows-restored observer notification. Maybe this test case helps us to track down this particular problem as filed as bug 1382162.
Depends on: 1433873
OS: Unspecified → All
Priority: -- → P5
Hardware: Unspecified → All
Version: Version 3 → Trunk
I would suggest we temporarily skip this test until I was able to land a fix for bug 1433873. Then we can retry if something has been changed.
Comment on attachment 8960799 [details]
Bug 1430717 - Skip test_cli_arguments.py TestCommandLineArguments.test_startup_timeout.

https://reviewboard.mozilla.org/r/229528/#review235274
Attachment #8960799 - Flags: review?(hskupin) → review+
Pushed by hskupin@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/84c7300864d7
Skip test_cli_arguments.py TestCommandLineArguments.test_startup_timeout. r=whimboo
Summary: test_cli_arguments.py TestCommandLineArguments.test_startup_timeout causes hangs after Firefox restart on Windows → Intermittent test_cli_arguments.py TestCommandLineArguments.test_startup_timeout causes hangs after Firefox restart on Windows
Pushed by hskupin@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/3e89ff5068b7
Re-enable test_cli_arguments.py TestCommandLineArguments.test_startup_timeout. r=ato
https://hg.mozilla.org/mozilla-central/rev/3e89ff5068b7
Status: NEW → RESOLVED
Closed: 10 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla65
So this is still happening but with a very low intermittent rate:

https://treeherder.mozilla.org/logviewer.html#?job_id=207453363&repo=autoland

Sebastian, can we please make sure that all the 1000s timeout failure for Mn jobs are getting classified against this bug instead of bug 1391545? What would we have to change for the summary to make that better classifiable?
Flags: needinfo?(aryx.bugmail)
Keywords: test-disabled
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Target Milestone: mozilla65 → ---
This should be fixed now since my patch on bug 1416747 landed.
Assignee: nobody → hskupin
Status: REOPENED → RESOLVED
Closed: 10 months ago10 months ago
Flags: needinfo?(aryx.bugmail)
Resolution: --- → FIXED
Target Milestone: --- → mozilla65
You need to log in before you can comment on or make changes to this bug.