Treeherder Bug Filer Reporter
	Description • 7 years ago
treeherder

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

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

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

[task 2017-09-07T20:47:43.824048Z] 20:47:43     INFO - STDOUT: PASSED
[task 2017-09-07T20:47:43.827637Z] 20:47:43     INFO - PID 2775 | 1504817263824	Marionette	INFO	New connections will no longer be accepted
[task 2017-09-07T20:47:44.078201Z] 20:47:44     INFO - PID 2775 | [Child 5314] WARNING: pipe error (15): Connection reset by peer: file /build/firefox-S0wyi8/firefox-55.0.2+build1/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 353
[task 2017-09-07T20:47:44.078333Z] 20:47:44     INFO - PID 2775 | [Child 5314] WARNING: pipe error (3): Connection reset by peer: file /build/firefox-S0wyi8/firefox-55.0.2+build1/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 353
[task 2017-09-07T20:47:44.081676Z] 20:47:44     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/sessions/new_session/create.py::test_valid[test:extension-True-body0] 
[task 2017-09-07T20:47:44.097970Z] 20:47:44     INFO - PID 2775 | 1504817264083	geckodriver::marionette	INFO	Starting browser /usr/bin/firefox with args ["-marionette"]
[task 2017-09-07T20:47:45.169426Z] 20:47:45     INFO - PID 2775 | 1504817265163	Marionette	INFO	Enabled via --marionette
[task 2017-09-07T20:47:46.459729Z] 20:47:46     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/sessions/new_session/create.py | expected OK

	Henrik Skupin [:whimboo][⌚️UTC+1]
	Comment 2 • 7 years ago

[task 2017-09-07T20:47:44.097970Z] 20:47:44     INFO - PID 2775 | 1504817264083	geckodriver::marionette	INFO	Starting browser /usr/bin/firefox with args ["-marionette"]
[task 2017-09-07T20:47:45.169426Z] 20:47:45     INFO - PID 2775 | 1504817265163	Marionette	INFO	Enabled via --marionette
[task 2017-09-07T20:47:46.459729Z] 20:47:46     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/sessions/new_session/create.py | expected OK

We time out after 60s now given that Marionette client is not correctly used by web-platform tests. Bug 1397675 might help here.

	Henrik Skupin [:whimboo][⌚️UTC+1]
	Comment 4 • 7 years ago

So something is strange on those failures:

[task 2017-09-13T20:34:49.488910Z] 20:34:49     INFO - PID 2940 | 1505334889484	geckodriver::marionette	INFO	Starting browser /usr/bin/firefox with args ["-marionette"]
[task 2017-09-13T20:34:50.158721Z] 20:34:50     INFO - PID 2940 | 1505334890153	Marionette	INFO	Enabled via --marionette
[task 2017-09-13T20:34:50.913911Z] 20:34:50     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/sessions/new_session/create.py | expected OK
[task 2017-09-13T20:34:50.914782Z] 20:34:50     INFO - TEST-INFO took 125012ms

We start the browser and timeout immediately as if a timeout value of 0s is set. James, any idea why this could happen? Sadly the log is not helpful at all.

	James Graham [:jgraham] Assignee
	Comment 5 • 7 years ago

The test creates multiple sessions, so the timeout is unrelated to the session that was just created. It has an overall 125s timeout. We are hitting that limit intermittently. The right way to fix this is probably to split the test up like I originally did.

	Joel Maher ( :jmaher ) (UTC -8)
	Comment 7 • 7 years ago

the failure rate is remaining high:
https://brasstacks.mozilla.com/orangefactor/index.html?display=Bug&bugid=1397912

linux64 opt/pgo specifically.

:jgraham, based on comment 5, do you have a pending patch for this?  If not, do you know who on the team would be a good candidate for writing that patch or any patch to fix this issue?

	Andreas Tolfsen ❲:ato❳
	Comment 9 • 7 years ago
mozreview-review

Comment on attachment 8911112 [details]
Bug 1397912 - Split wdspec session creation tests for intermittent timeouts,

https://reviewboard.mozilla.org/r/182604/#review187884

	Pulsebot
	Comment 10 • 7 years ago

Pushed by james@hoppipolla.co.uk:
https://hg.mozilla.org/integration/autoland/rev/9ebe05258185
Split wdspec session creation tests for intermittent timeouts, r=ato

	Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)
	Comment 11 • 7 years ago
bugherder

https://hg.mozilla.org/mozilla-central/rev/9ebe05258185

	Ryan VanderMeulen [:RyanVM]
	Comment 12 • 7 years ago
bugherder uplift

https://hg.mozilla.org/releases/mozilla-beta/rev/a6d9c15124a8

	BMO Automation
	Comment 14 • 1 year ago

Moving bug to Remote Protocol::Marionette component per bug 1815831.