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

Reporter

Description

•

6 years ago

treeherder

Filed by: dluca [at] mozilla.com

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

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

Comment hidden (Intermittent Failures Robot)

15 failures in 121 pushes (0.124 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* mozilla-inbound: 9
* autoland: 6

Platform breakdown:
* windows10-64: 6
* windows7-32: 4
* linux64: 3
* osx-10-10: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-15&endday=2018-05-15&tree=trunk

Henri Sivonen (:hsivonen)

Updated

•

6 years ago

Priority: -- → P2

Tiberius Oros[:tiberius_oros]

Comment 2

•

6 years ago

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

Comment hidden (Intermittent Failures Robot)

47 failures in 129 pushes (0.364 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* try: 15
* autoland: 15
* mozilla-inbound: 10
* mozilla-central: 7

Platform breakdown:
* windows10-64: 19
* windows7-32: 16
* windows10-64-nightly: 3
* linux64: 3
* osx-10-10: 2
* linux32: 2
* windows7-32-nightly: 1
* windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-16&endday=2018-05-16&tree=trunk

Cosmin Sabou [:CosminS]

Comment 4

•

6 years ago

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]

Comment hidden (Intermittent Failures Robot)

32 failures in 119 pushes (0.269 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* autoland: 14
* mozilla-inbound: 7
* mozilla-central: 6
* try: 5

Platform breakdown:
* windows10-64: 12
* windows7-32: 8
* linux64: 6
* osx-10-10: 3
* windows7-32-nightly: 1
* windows10-64-nightly: 1
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-17&endday=2018-05-17&tree=trunk

Comment hidden (Intermittent Failures Robot)

27 failures in 99 pushes (0.273 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* mozilla-inbound: 11
* autoland: 9
* mozilla-central: 4
* try: 3

Platform breakdown:
* windows7-32: 10
* windows10-64: 8
* linux64: 4
* osx-10-10: 2
* macosx64-devedition: 1
* linux32-nightly: 1
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-18&endday=2018-05-18&tree=trunk

Comment hidden (Intermittent Failures Robot)

24 failures in 32 pushes (0.75 failures/push) were associated with this bug yesterday.    

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. ** 

Repository breakdown:
* mozilla-inbound: 10
* autoland: 8
* mozilla-central: 5
* try: 1

Platform breakdown:
* windows10-64: 10
* windows7-32: 9
* linux64: 3
* osx-10-10: 1
* linux32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-19&endday=2018-05-19&tree=trunk

Raul Gurzau (:RaulG)

Comment 8

•

6 years ago

Attached patch bug1456284.patch (obsolete) — Details — Splinter Review

Attachment #8979087 - Flags: review?(gbrown)

Raul Gurzau (:RaulG)

Comment 9

•

6 years ago

Attached patch bug1461854.patch — Details — Splinter Review

Attachment #8979087 - Attachment is obsolete: true

Attachment #8979087 - Flags: review?(gbrown)

Attachment #8979088 - Flags: review?(gbrown)

Comment hidden (Intermittent Failures Robot)

188 failures in 639 pushes (0.294 failures/push) were associated with this bug in the last 7 days. 

This is the #6 most frequent failure this week. 

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **   

Repository breakdown:
* autoland: 60
* mozilla-inbound: 55
* mozilla-central: 38
* try: 35

Platform breakdown:
* windows10-64: 69
* windows7-32: 63
* linux64: 20
* osx-10-10: 14
* windows7-32-nightly: 5
* windows10-64-nightly: 5
* linux32: 4
* windows10-64-devedition: 2
* windows7-32-devedition: 1
* macosx64-nightly: 1
* macosx64-devedition: 1
* linux64-jsdcov: 1
* linux32-nightly: 1
* linux32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-14&endday=2018-05-20&tree=trunk

Comment hidden (Intermittent Failures Robot)

49 failures in 86 pushes (0.57 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* try: 21
* autoland: 13
* mozilla-inbound: 9
* mozilla-central: 6

Platform breakdown:
* windows7-32: 10
* windows10-64: 10
* osx-10-10: 8
* linux32: 8
* linux64: 4
* linux64-qr: 2
* linux32-devedition: 2
* windows7-32-nightly: 1
* windows10-64-nightly: 1
* linux64-jsdcov: 1
* linux64-ccov: 1
* linux32-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-21&endday=2018-05-21&tree=trunk

Comment hidden (Intermittent Failures Robot)

22 failures in 111 pushes (0.198 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* mozilla-inbound: 8
* try: 7
* autoland: 6
* mozilla-central: 1

Platform breakdown:
* windows7-32: 6
* windows10-64: 4
* osx-10-10: 3
* windows10-64-devedition: 2
* linux64: 2
* linux32: 2
* windows7-32-nightly: 1
* macosx64-nightly: 1
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-22&endday=2018-05-22&tree=trunk

Robert O'Callahan (:roc) (email my personal email if necessary)

Comment 13

•

6 years ago

We caught this bug in our Pernosco debugger.
https://pernos.co/debug/N5bf6WtIgq_UaPuCnpbf5A/index.html
Access details here: https://mana.mozilla.org/wiki/display/TS/Debugging+with+Pernosco

Andrea Marchesini [:baku]

Assignee

Comment 14

•

6 years ago

Attached patch time.patch — Details — Splinter 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)

Andrea Marchesini [:baku]

Assignee

Comment 15

•

6 years ago

Comment on attachment 8979088 [details] [diff] [review]
bug1461854.patch

This patch is not needed anymore.

Attachment #8979088 - Flags: review?(gbrown)

Tom Ritter [:tjr] (OOTO until 4/30 at least)

Updated

•

6 years ago

Attachment #8979884 - Flags: review?(tom) → review+

Pulsebot

Comment 16

•

6 years ago

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

Comment hidden (Intermittent Failures Robot)

37 failures in 104 pushes (0.356 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* autoland: 18
* mozilla-inbound: 14
* mozilla-central: 3
* try: 2

Platform breakdown:
* windows7-32: 12
* windows10-64: 11
* osx-10-10: 5
* linux64-qr: 4
* linux64: 4
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-23&endday=2018-05-23&tree=trunk

Eliza Balazs [:ebalazs_]

Comment 18

•

6 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/98a9787f56df

Status: NEW → RESOLVED

Closed: 6 years ago

status-firefox62: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → mozilla62

Comment hidden (obsolete)

Recent failure log: https://bugzilla.mozilla.org/show_bug.cgi?id=1463107

11:42:15     INFO -  46 INFO TEST-START | dom/console/tests/test_timer.html
11:47:15     INFO -  TEST-INFO | started process screenshot
11:47:15     INFO -  TEST-INFO | screenshot: exit 0
11:47:15     INFO -  Buffered messages logged at 11:42:15
11:47:15     INFO -  47 INFO TEST-PASS | dom/console/tests/test_timer.html | Console.time received!
11:47:15     INFO -  Buffered messages finished
11:47:15    ERROR -  48 INFO TEST-UNEXPECTED-FAIL | dom/console/tests/test_timer.html | Test timed out.
11:47:15     INFO -      reportError@SimpleTest/TestRunner.js:121:7
11:47:15     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:47:15     INFO -      TestRunner.resetTests@SimpleTest/TestRunner.js:397:3
11:47:15     INFO -      TestRunner.runNextTest@SimpleTest/TestRunner.js:483:11
11:47:15     INFO -      TestRunner.testUnloaded@SimpleTest/TestRunner.js:677:9
11:47:15     INFO -      @SimpleTest/iframe-between-tests.html:11:3
11:47:15     INFO -      EventListener.handleEvent*@SimpleTest/iframe-between-tests.html:9:1
11:47:15     INFO -  Not taking screenshot here: see the one that was previously logged
11:47:15    ERROR -  49 INFO TEST-UNEXPECTED-FAIL | dom/console/tests/test_timer.html | Test timed out.
11:47:15     INFO -      reportError@SimpleTest/TestRunner.js:121:7
11:47:15     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7

Andrea Marchesini [:baku]

Assignee

Comment 20

•

6 years ago

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)

Eliza Balazs [:ebalazs_]

Comment 21

•

6 years ago

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

Eliza Balazs [:ebalazs_]

Updated

•

6 years ago

Flags: needinfo?(amarchesini)

Eliza Balazs [:ebalazs_]

Updated

•

6 years ago

Flags: needinfo?(amarchesini)

Pulsebot

Comment 22

•

6 years ago

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

Cristian Brindusan [:cbrindusan]

Comment 23

•

6 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/065fdd697004

Status: REOPENED → RESOLVED

Closed: 6 years ago → 6 years ago

Resolution: --- → FIXED

Comment hidden (Intermittent Failures Robot)

35 failures in 109 pushes (0.321 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* autoland: 15
* mozilla-central: 14
* mozilla-inbound: 5
* try: 1

Platform breakdown:
* windows10-64: 14
* windows7-32: 9
* osx-10-10: 6
* linux64: 2
* windows7-32-nightly: 1
* windows10-64-nightly: 1
* macosx64-nightly: 1
* linux64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-24&endday=2018-05-24&tree=trunk

Eliza Balazs [:ebalazs_]

Updated

•

6 years ago

Flags: needinfo?(ebalazs)

Comment hidden (Intermittent Failures Robot)

156 failures in 632 pushes (0.247 failures/push) were associated with this bug in the last 7 days. 

This is the #10 most frequent failure this week. 

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **   

Repository breakdown:
* autoland: 57
* try: 40
* mozilla-inbound: 34
* mozilla-central: 25

Platform breakdown:
* windows7-32: 46
* windows10-64: 39
* osx-10-10: 21
* linux64: 15
* linux32: 13
* linux64-qr: 7
* windows7-32-nightly: 4
* windows10-64-devedition: 3
* windows10-64-nightly: 2
* macosx64-nightly: 1
* linux64-nightly: 1
* linux64-jsdcov: 1
* linux64-ccov: 1
* linux32-nightly: 1
* linux32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1461854&startday=2018-05-21&endday=2018-05-27&tree=trunk

Nobody; OK to take it and work on it

Updated

•

5 years ago

Component: DOM → DOM: Core & HTML

bug1456284.patch 6 years ago Raul Gurzau (:RaulG) 1.03 KB, patch		Details \| Diff \| Splinter Review
bug1461854.patch 6 years ago Raul Gurzau (:RaulG) 513 bytes, patch		Details \| Diff \| Splinter Review
time.patch 6 years ago Andrea Marchesini [:baku] 1.31 KB, patch	tjr : review+	Details \| Diff \| Splinter Review