Closed Bug 1461854 Opened 2 years ago Closed 2 years ago

Intermittent dom/console/tests/test_timer.html | Test timed out.

Categories

(Core :: DOM: Core & HTML, defect, P2)

defect

Tracking

()

RESOLVED FIXED
mozilla62
Tracking Status
firefox62 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: baku)

Details

(Keywords: intermittent-failure, Whiteboard: [retriggered][stockwell disable-recommended])

Attachments

(2 files, 1 obsolete file)

Priority: -- → P2
There have been 41 failures in the last week, according to Orange Factor.

Failures per platform and build type:

windows10-64 / opt & pgo - 15
Windows 7 /  opt & pgo - 13
OS X 10.10 / opt - 5
Linux x64 / opt & pgo - 4
Linux / opt - 1
macosx64-nightly / opt - 1
windows10-64-nightly / opt - 1
windows7-32-nightly / opt - 1

Recent relevant log file:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=178713552&lineNumber=7138

Snippet with the failure: 

08:40:07     INFO -  1989 INFO TEST-START | dom/console/tests/test_timer.html
08:40:12     INFO -  GECKO(5432) | [Parent 5432, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
08:45:36     INFO -  TEST-INFO | started process screenshot
08:45:36     INFO -  TEST-INFO | screenshot: exit 0
08:45:36     INFO -  Buffered messages logged at 08:40:07
08:45:36     INFO -  1990 INFO TEST-PASS | dom/console/tests/test_timer.html | Console.time received!
08:45:36     INFO -  Buffered messages finished
08:45:36    ERROR -  1991 INFO TEST-UNEXPECTED-FAIL | dom/console/tests/test_timer.html | Test timed out.
08:45:36     INFO -      reportError@SimpleTest/TestRunner.js:121:7
08:45:36     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:45:36     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
This started as a test verify failure here https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&revision=e42c3ae89de065ba9dc4cbcdebd9c5f109d111bd&filter-searchStr=opt-test-verify-e10s&selectedJob=178595095 then it became a tier1 fail one push later here http://tinyurl.com/yacgzct2 .

Looking in mercurial to find where there were modifications to this file and found this earlier push also from Andrea https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&filter-searchStr=opt-mochitest-plain-headless-e10s-1&tochange=12bfd7a50a79e0509aabf9e97b72013a93d747d4&fromchange=69a0dda97ae5af2d3735c2925a615510210a2d4b&selectedJob=178935214
That seeems to be the culprit. It fails around 5 times out of 20 jobs now.

Andrea, could you please take a look at this? Thank you.
Flags: needinfo?(amarchesini)
Whiteboard: [stockwell needswork:owner] → [stockwell needswork:owner][retriggered]
Attached patch bug1456284.patch (obsolete) — Splinter Review
Attachment #8979087 - Flags: review?(gbrown)
Attached patch bug1461854.patchSplinter Review
Attachment #8979087 - Attachment is obsolete: true
Attachment #8979087 - Flags: review?(gbrown)
Attachment #8979088 - Flags: review?(gbrown)
Attached patch time.patchSplinter Review
Using Pernosco, it took me a couple of minutes to fix this issue.
Assignee: nobody → amarchesini
Flags: needinfo?(amarchesini)
Attachment #8979884 - Flags: review?(tom)
Comment on attachment 8979088 [details] [diff] [review]
bug1461854.patch

This patch is not needed anymore.
Attachment #8979088 - Flags: review?(gbrown)
Pushed by amarchesini@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/98a9787f56df
dom/console/tests/test_timer.html must disable reduce-timer-precision, r=tjr
https://hg.mozilla.org/mozilla-central/rev/98a9787f56df
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla62
Its' important to know if this failure happens with or without my patch. Can I have the try push URL?
Flags: needinfo?(amarchesini) → needinfo?(ebalazs)
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=179990087&repo=mozilla-central&lineNumber=1826

12:04:54     INFO -  91 INFO TEST-START | dom/console/tests/test_timer.html
12:09:54     INFO -  TEST-INFO | started process screenshot
12:09:54     INFO -  TEST-INFO | screenshot: exit 0
12:09:54     INFO -  Buffered messages logged at 12:04:54
12:09:54     INFO -  92 INFO TEST-PASS | dom/console/tests/test_timer.html | Console.time received!
12:09:54     INFO -  Buffered messages finished
12:09:54    ERROR -  93 INFO TEST-UNEXPECTED-FAIL | dom/console/tests/test_timer.html | Test timed out.
12:09:54     INFO -      reportError@SimpleTest/TestRunner.js:121:7
12:09:54     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      TestRunner.resetTests@SimpleTest/TestRunner.js:397:3
12:09:54     INFO -      TestRunner.runNextTest@SimpleTest/TestRunner.js:483:11
12:09:54     INFO -      TestRunner.testUnloaded@SimpleTest/TestRunner.js:677:9
12:09:54     INFO -      @SimpleTest/iframe-between-tests.html:11:3
12:09:54     INFO -      EventListener.handleEvent*@SimpleTest/iframe-between-tests.html:9:1
12:09:54     INFO -  Not taking screenshot here: see the one that was previously logged
12:09:54    ERROR -  94 INFO TEST-UNEXPECTED-FAIL | dom/console/tests/test_timer.html | Test timed out.
12:09:54     INFO -      reportError@SimpleTest/TestRunner.js:121:7
12:09:54     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:09:54     INFO -      TestRunner.resetTests@SimpleTest/TestRunner.js:397:3
12:09:54     INFO -      TestRunner.runNextTest@SimpleTest/TestRunner.js:483:11
12:09:54     INFO -      TestRunner.testUnloaded@SimpleTest/TestRunner.js:677:9
12:09:54     INFO -      @SimpleTest/iframe-between-tests.html:11:3
Flags: needinfo?(amarchesini)
Flags: needinfo?(amarchesini)
Pushed by amarchesini@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/065fdd697004
Fixing dom/console/tests/test_timer.html allowing duration to be >= 0, r=me
https://hg.mozilla.org/mozilla-central/rev/065fdd697004
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → FIXED
Flags: needinfo?(ebalazs)
Component: DOM → DOM: Core & HTML
You need to log in before you can comment on or make changes to this bug.