1588148 - Intermittent dom/html/test/test_formSubmission.html | Test timed out.

Status: RESOLVED WORKSFORME

(Core :: DOM: Service Workers, defect, P3)

Description

[Treeherder Bug Filer]

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=270856467&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/TNN04q0IScCyjJfUFghhZg/runs/0/artifacts/public/logs/live_backing.log

---

INFO - TEST-PASS | dom/html/test/test_formSubmission.html | controlled by the SW we expected 
[task 2019-10-11T11:30:14.685Z] 11:30:14     INFO - became controlled by ServiceWorker.
[task 2019-10-11T11:30:14.685Z] 11:30:14     INFO - starting test variant: ServiceWorker that proxies all fetches
[task 2019-10-11T11:30:14.685Z] 11:30:14     INFO - Buffered messages finished
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Test timed out. 
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     reportError@SimpleTest/TestRunner.js:121:22
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     hookupTests@SimpleTest/setup.js:273:12
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO -     hookup@SimpleTest/setup.js:253:20
[task 2019-10-11T11:30:14.686Z] 11:30:14     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1570789181%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | This test left a service worker registered without cleaning it up 
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     afterCleanup@SimpleTest/SimpleTest.js:1192:28
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     async*executeCleanupFunction@SimpleTest/SimpleTest.js:1232:13
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1251:5
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     killTest@SimpleTest/TestRunner.js:130:22
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     async*delayedKillTest@SimpleTest/TestRunner.js:159:17
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:157:17
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.607Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     hookupTests@SimpleTest/setup.js:273:12
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO -     hookup@SimpleTest/setup.js:253:20
[task 2019-10-11T11:30:15.608Z] 11:30:15     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1570789181%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2019-10-11T11:30:15.609Z] 11:30:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-11T11:30:15.609Z] 11:30:15     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Left over worker: http://mochi.test:8888/tests/dom/html/test/sw_formSubmission.js?proxy-fetch (scope: 

Comment 1

[Intermittent Failures Robot]

2 failures in 3801 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

• windows10-64-ccov: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-10-07&endday=2019-10-13&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 3964 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 3

[Intermittent Failures Robot]

2 failures in 4182 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows7-32-shippable: 1
• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 4314 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-10-28&endday=2019-11-03&tree=all

Comment 5

[Intermittent Failures Robot]

4 failures in 4850 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4

Platform breakdown:

• windows10-64-qr: 1
• windows10-64: 1
• windows10-64-shippable-qr: 1
• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-11-04&endday=2019-11-10&tree=all

Comment 6

[Intermittent Failures Robot]

24 failures in 4207 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• mozilla-central: 7
• autoland: 16

Platform breakdown:

• windows10-64: 7
• windows10-64-qr: 4
• windows10-64-shippable: 7
• windows7-32: 1
• macosx1014-64: 1
• windows10-64-shippable-qr: 3
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-11-11&endday=2019-11-17&tree=all

Comment 7

[Intermittent Failures Robot]

24 failures in 5444 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 16
• mozilla-central: 6
• try: 2

Platform breakdown:

• windows10-64-shippable: 7
• linux64-qr: 1
• windows10-64: 7
• windows10-64-ccov: 1
• windows10-64-qr: 5
• windows10-64-shippable-qr: 2
• linux64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-11-18&endday=2019-11-24&tree=all

Comment 8

[Intermittent Failures Robot]

31 failures in 4331 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #28 most frequent failure this week.

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

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

Repository breakdown:

• try: 5
• mozilla-central: 7
• autoland: 19

Platform breakdown:

• windows10-64-qr: 8
• windows10-64: 7
• windows10-64-shippable: 3
• windows10-64-shippable-qr: 6
• windows7-32-shippable: 1
• linux64-qr: 2
• linux64-shippable: 1
• linux64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 9

[Andreea Pavel [:apavel]]

There are 26 total failures in the last 7 days on windows10-64-shippable-qr opt, windows10-64 debug and opt

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=279632440&repo=autoland&lineNumber=15543

[task 2019-12-04T20:22:40.546Z] 20:22:40 INFO - TEST-START | dom/html/test/test_formSubmission.html
[task 2019-12-04T20:22:40.612Z] 20:22:40 INFO - GECKO(9956) | [Parent 1884, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file z:/build/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-04T20:22:40.852Z] 20:22:40 INFO - [9568, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-12-04T20:22:40.974Z] 20:22:40 INFO - GECKO(9956) | [Parent 1884, Socket Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80470002: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 82
[task 2019-12-04T20:22:40.974Z] 20:22:40 INFO - GECKO(9956) | [Parent 1884, Socket Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80470002: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 82
[task 2019-12-04T20:22:40.974Z] 20:22:40 INFO - GECKO(9956) | [Parent 1884, Socket Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80470002: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 82
[task 2019-12-04T20:22:40.974Z] 20:22:40 INFO - GECKO(9956) | [Parent 1884, Socket Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80470002: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 82
[task 2019-12-04T20:22:40.993Z] 20:22:40 INFO - [9568, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-12-04T20:22:41.011Z] 20:22:41 INFO - GECKO(9956) | [Parent 1884, Main Thread] WARNING: This file has not been opened (or could not be opened). Sending an invalid file descriptor to the other process!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 583
[task 2019-12-04T20:22:41.013Z] 20:22:41 INFO - GECKO(9956) | [Parent 1884, Main Thread] WARNING: This file has not been opened (or could not be opened). Sending an invalid file descriptor to the other process!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 583
[task 2019-12-04T20:22:41.013Z] 20:22:41 INFO - GECKO(9956) | [Parent 1884, Main Thread] WARNING: This file has not been opened (or could not be opened). Sending an invalid file descriptor to the other process!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 583
[task 2019-12-04T20:22:41.013Z] 20:22:41 INFO - GECKO(9956) | [Parent 1884, Main Thread] WARNING: This file has not been opened (or could not be opened). Sending an invalid file descriptor to the other process!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 583
[task 2019-12-04T20:22:41.014Z] 20:22:41 INFO - GECKO(9956) | [Parent 1884, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file z:/build/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-04T20:22:41.014Z] 20:22:41 INFO - GECKO(9956) | [Child 10192, Main Thread] WARNING: Received a bad file descriptor index!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 622
[task 2019-12-04T20:22:41.017Z] 20:22:41 INFO - GECKO(9956) | [Child 10192, Main Thread] WARNING: Received a bad file descriptor index!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 622
[task 2019-12-04T20:22:41.017Z] 20:22:41 INFO - GECKO(9956) | [Child 10192, Main Thread] WARNING: Received a bad file descriptor index!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 622
[task 2019-12-04T20:22:41.019Z] 20:22:41 INFO - GECKO(9956) | [Child 10192, Main Thread] WARNING: Received a bad file descriptor index!: file z:/build/build/src/netwerk/base/nsFileStreams.cpp, line 622
[task 2019-12-04T20:22:41.150Z] 20:22:41 INFO - [9568, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173

[task 2019-12-04T20:28:06.989Z] 20:28:06 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-04T20:28:06.989Z] 20:28:06 INFO - Buffered messages finished
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Test timed out.
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.990Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - hookup@SimpleTest/setup.js:253:20
[task 2019-12-04T20:28:06.991Z] 20:28:06 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1575489491%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | This test left a service worker registered without cleaning it up
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - afterCleanup@SimpleTest/SimpleTest.js:1192:28
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - asyncexecuteCleanupFunction@SimpleTest/SimpleTest.js:1232:13
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - SimpleTest.finish@SimpleTest/SimpleTest.js:1251:5
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - killTest@SimpleTest/TestRunner.js:130:22
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - asyncdelayedKillTest@SimpleTest/TestRunner.js:159:17
[task 2019-12-04T20:28:07.902Z] 20:28:07 INFO - setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:157:17

Hsin-Yi, can you assign someone to take a look?

Comment 10

[Hsin-Yi Tsai (she/her) [:hsinyi]]

John, can you help take a quick look at this?

Comment 11

[John Dai[:jdai]]

The first log[1] fails at running to ServiceWorker that proxies all fetch[2] until timeout, the second log[3] fails after running expected URL part [4]. However, I only see it happened sometimes, maybe we can consider increase timeout.

[1] https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=279933393&repo=autoland&lineNumber=15534
[2] https://searchfox.org/mozilla-central/rev/ea63a0888d406fae720cf24f4727d87569a8cab5/dom/html/test/test_formSubmission.html#889
[3] https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=279632440&repo=autoland&lineNumber=15543
[4] https://searchfox.org/mozilla-central/rev/ea63a0888d406fae720cf24f4727d87569a8cab5/dom/html/test/test_formSubmission.html#660

Comment 12

[John Dai[:jdai]]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=185b587179914686239710d5046469e17f65e92c&group_state=expanded

Comment 13

[Intermittent Failures Robot]

29 failures in 5144 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

This is the #43 most frequent failure this week.

Repository breakdown:

• try: 3
• mozilla-central: 7
• autoland: 19

Platform breakdown:

• windows10-64-qr: 4
• windows10-64: 12
• windows10-64-shippable-qr: 2
• windows10-64-shippable: 7
• linux64-qr: 1
• windows7-32: 1
• linux64: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-12-02&endday=2019-12-08&tree=all

Comment 14

[Andreea Pavel [:apavel]]

Above try push has a tier2 TV failure with this bug and a similar failure, a tier2 fission dt failure TEST-UNEXPECTED-TIMEOUT | dom/html/test/test_formSubmission.html | application timed out after 370 seconds with no output

Fission log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=280032030&repo=try&lineNumber=11745

task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | expected URL part
[task 2019-12-06T17:08:08.365Z] 17:08:08 INFO - Buffered messages finished
[task 2019-12-06T17:08:08.366Z] 17:08:08 ERROR - TEST-UNEXPECTED-TIMEOUT | dom/html/test/test_formSubmission.html | application timed out after 370 seconds with no output
[task 2019-12-06T17:08:08.366Z] 17:08:08 ERROR - Force-terminating active process(es).
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - Determining child pids from psutil...
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - [6800]
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - ==> process 6800 launched child process 7240 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.0.632708853\2103279195" -parentBuildID 20191206153903 -prefsHandle 2248 -prefMapHandle 2240 -prefsLen 1 -prefMapSize 223238 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 2304 gpu)
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - ==> process 6800 launched child process 7352 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.6.326328020\1336583775" -childID 1 -isForBrowser -prefsHandle 2200 -prefMapHandle 2196 -prefsLen 2195 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 2940 tab)
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - ==> process 6800 launched child process 2812 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.13.1160093578\553501226" -childID 2 -isForBrowser -prefsHandle 3148 -prefMapHandle 3144 -prefsLen 2292 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 3156 tab)
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - ==> process 6800 launched child process 3340 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.20.52258973\1072865236" -childID 3 -isForBrowser -prefsHandle 3288 -prefMapHandle 3292 -prefsLen 2353 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 3156 tab)
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - ==> process 6800 launched child process 10212 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.27.235446373\984987352" -childID 4 -isForBrowser -prefsHandle 4324 -prefMapHandle 4392 -prefsLen 11085 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 4460 tab)
[task 2019-12-06T17:08:08.366Z] 17:08:08 INFO - ==> process 6800 launched child process 5720 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.34.803139874\1621477121" -childID 5 -isForBrowser -prefsHandle 4852 -prefMapHandle 4840 -prefsLen 11154 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 4732 tab)
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - ==> process 6800 launched child process 880 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.41.1401095519\1913377092" -childID 6 -isForBrowser -prefsHandle 4952 -prefMapHandle 4968 -prefsLen 11154 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 5064 tab)
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - ==> process 6800 launched child process 2960 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.48.1651381468\1892944091" -childID 7 -isForBrowser -prefsHandle 4856 -prefMapHandle 4872 -prefsLen 11203 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 4916 tab)
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - ==> process 6800 launched child process 10160 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.55.525870759\2113335900" -childID 8 -isForBrowser -prefsHandle 4828 -prefMapHandle 5040 -prefsLen 11283 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 4824 tab)
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - ==> process 6800 launched child process 10868 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.62.880319720\694471178" -childID 9 -isForBrowser -prefsHandle 5348 -prefMapHandle 5144 -prefsLen 11691 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 5036 tab)
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - ==> process 6800 launched child process 6264 ("Z:\task_1575648921\build\application\firefox\firefox.exe" -contentproc --channel="6800.69.1508296495\1910421318" -childID 10 -isForBrowser -prefsHandle 4808 -prefMapHandle 3700 -prefsLen 11905 -prefMapSize 223238 -parentBuildID 20191206153903 -greomni "Z:\task_1575648921\build\application\firefox\omni.ja" -appomni "Z:\task_1575648921\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1575648921\build\application\firefox\browser" - 6800 "\.\pipe\gecko-crash-server-pipe.6800" 4148 tab)
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - Found child pids: set([880, 2960, 10160, 10212, 7240, 3340, 6800, 5720, 10868, 7352, 6264, 2812])
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - Failed to get child procs
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - Killing process: 880
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - TEST-INFO | started process screenshot
[task 2019-12-06T17:08:08.367Z] 17:08:08 INFO - TEST-INFO | screenshot: exit 0

Comment 15

[John Dai[:jdai]]

Looks like tier2 TV failure[1] and tier2 fission failure[2] fail at running ServiceWorker that proxies all fetches[3]. I'm not familiar with ServiceWorker's code, please redirect to the corresponding person to get further analysis.

[1] https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=280031387&revision=185b587179914686239710d5046469e17f65e92c
[2] https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=280032030&revision=185b587179914686239710d5046469e17f65e92c
[3] https://searchfox.org/mozilla-central/rev/23d4bffcad365e68d2d45776017056b76ca9a968/dom/html/test/test_formSubmission.html#889

Comment 16

[Intermittent Failures Robot]

22 failures in 4867 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2
• autoland: 9
• mozilla-central: 11

Platform breakdown:

• windows10-64: 11
• windows10-64-shippable: 6
• linux64-qr: 1
• windows10-64-qr: 2
• windows10-64-shippable-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-12-09&endday=2019-12-15&tree=all

Comment 17

[Andreea Pavel [:apavel]]

Jens, can you please take a look at John's comment?

Comment 18

[Jens Stutte [:jstutte]]

:perry Can you please have a short look if this deserves higher priority or gives some hint on SW lifecycle problems you are chasing anyway?

:john I see a very oscillating orange factor over time, varying between 0 and 0.1 with no clear pattern to look for in code changes. Can we correlate this curve with anything else? For example load on test systems (it happens mostly during tests on Windows) ? Do we have the historical data of execution times for this test in case of success (it might be me to not know where to find it) ? To know this might help in reproducing.

Comment 19

[John Dai[:jdai]]

I will try to reproduce at a local windows machine. If it's unreproducible, I'll use Pernosco for TV failure. I will trace from raw log to know load on test systems. I don't know if there is historical data of execution times for this test TBH.

Comment 20

[Perry Jiang [:perry] [no longer employee, use ni?]]

I wasn't able to reproduce/figure out what's going wrong...jdai did you have any luck reproducing?

Comment 21

[John Dai[:jdai]]

(In reply to Perry Jiang [:perry] from comment #20)

I wasn't able to reproduce/figure out what's going wrong...jdai did you have any luck reproducing?

I wasn't able to reproduce on my windows locally since the test fails occasionally, I'd suggest using Pernosco.

Comment 22

[Intermittent Failures Robot]

30 failures in 4582 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

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

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

Repository breakdown:

• autoland: 19
• try: 5
• mozilla-central: 6

Platform breakdown:

• windows10-64-shippable-qr: 11
• windows10-64-qr: 11
• windows10-64: 4
• windows10-64-shippable: 2
• linux64: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-12-16&endday=2019-12-22&tree=all

Comment 23

[Intermittent Failures Robot]

17 failures in 1365 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #49 most frequent failure this week.

Repository breakdown:

• mozilla-central: 6
• autoland: 5
• try: 6

Platform breakdown:

• windows10-64-shippable-qr: 1
• windows10-64-shippable: 4
• linux64-qr: 1
• windows10-64-qr: 5
• windows10-64: 4
• linux64: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-12-23&endday=2019-12-29&tree=all

Comment 24

[Andrew Sutherland [:asuth] (he/him)]

From test logs it does look like we do just want to increase the timeout factor on this test. The timeout in at least one of the cases is happening at https://searchfox.org/mozilla-central/rev/a46d0fc0e7bf06b64a8d27e2cdbeeee94ecc7ad1/dom/html/test/test_formSubmission.html#819 inside the last test case at https://searchfox.org/mozilla-central/rev/a46d0fc0e7bf06b64a8d27e2cdbeeee94ecc7ad1/dom/html/test/test_formSubmission.html#889 which explains why this is only an intermittent.

Comment 25

[Andrew Sutherland [:asuth] (he/him)]

Attachment: Bug 1588148 - extend test timeout for long-running test. r=perry

Comment 26

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/c5f2b5b4a45f
extend test timeout for long-running test. r=perry

Comment 27

[Narcis Beleuzu [:NarcisB]]

Backed out for mochitest failures on test_formSubmission.html

Backout link: https://hg.mozilla.org/integration/autoland/rev/e8a5678266f78eb5f0b9b7c055d71f24f1b86290
Log link: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=283479842&repo=autoland&lineNumber=15905

Comment 28

[Andrew Sutherland [:asuth] (he/him)]

I was using the "browser" variant where requestLongerTimeout is available on the global, whereas for normal mochitests it's only on SimpleTest. Updating and re-landing.

Comment 29

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/dfc2cef99712
extend test timeout for long-running test. r=perry

Comment 30

[Oana Pop-Rus]

https://hg.mozilla.org/mozilla-central/rev/dfc2cef99712

Comment 31

[Intermittent Failures Robot]

24 failures in 1862 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #35 most frequent failure this week.

Repository breakdown:

• mozilla-central: 6
• autoland: 12
• try: 6

Platform breakdown:

• linux64: 2
• windows10-64-shippable: 6
• windows10-64: 4
• windows10-64-qr: 4
• windows7-32-shippable: 3
• windows10-64-shippable-qr: 4
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2019-12-30&endday=2020-01-05&tree=all

Comment 32

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Backed out for still frequent timeouts as discussed in bug 1607076 comment 3:
https://hg.mozilla.org/integration/autoland/rev/d2ac06fcafcd2e32244a33cae326d1beac166f42

Comment 35

[Intermittent Failures Robot]

30 failures in 4004 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

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

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

Repository breakdown:

• try: 6
• mozilla-central: 8
• autoland: 13
• mozilla-beta: 3

Platform breakdown:

• windows10-64-shippable-qr: 6
• windows10-64: 7
• windows7-32-shippable: 3
• linux1804-64-qr: 1
• windows10-64-shippable: 5
• macosx1014-64-shippable: 1
• windows10-64-devedition: 1
• windows10-64-qr: 5
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 36

[Dorel Luca [:dluca]]

This bug failed 30 times in the last 7 days. Occurs on Windows platforms on opt and debug build types.

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

Comment 37

[Intermittent Failures Robot]

22 failures in 2190 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #26 most frequent failure this week.

Repository breakdown:

• autoland: 13
• mozilla-central: 8
• try: 1

Platform breakdown:

• windows10-64-shippable-qr: 4
• windows10-64: 5
• windows10-64-qr: 6
• windows10-64-shippable: 6
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-01-27&endday=2020-02-02&tree=all

Comment 38

[Intermittent Failures Robot]

15 failures in 3364 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10
• mozilla-central: 3
• try: 2

Platform breakdown:

• windows7-32-shippable: 1
• windows10-64: 5
• windows10-64-shippable: 4
• windows10-64-qr: 3
• windows10-64-shippable-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-02-03&endday=2020-02-09&tree=all

Comment 39

[Intermittent Failures Robot]

19 failures in 903 pushes (0.021 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 2
• autoland: 4
• try: 12
• mozilla-beta: 1

Platform breakdown:

• windows10-64: 7
• windows7-32-shippable: 1
• windows10-64-qr: 9
• macosx1014-64: 1
• windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-02-13&endday=2020-02-13&tree=all

Comment 40

[Cosmin Sabou [:CosminS]]

This has:

• 25 total failures in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-02-10&endday=2020-02-17&tree=trunk&bug=1588148
• 80 total failures in the last 30 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-01-18&endday=2020-02-17&tree=trunk&bug=1588148

This fails mostly on windows debug & opt jobs and it's skipped on Android: https://searchfox.org/mozilla-central/source/dom/html/test/mochitest.ini#437
Andrew, any updates on this?

Comment 41

[Intermittent Failures Robot]

59 failures in 4690 pushes (0.013 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 30 times this week! Resolving this bug is a high priority. **

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

Repository breakdown:

• mozilla-beta: 6
• autoland: 16
• try: 28
• mozilla-central: 9

Platform breakdown:

• windows10-64: 13
• windows10-64-shippable-qr: 6
• windows10-64-qr: 22
• windows10-64-shippable: 11
• windows7-32-shippable: 3
• windows10-64-devedition: 2
• linux1804-64: 1
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-02-10&endday=2020-02-16&tree=all

Comment 42

[Andreea Pavel [:apavel]]

Jens can you please take a look at this? Is there someone else who can work on this?

Comment 43

[Intermittent Failures Robot]

16 failures in 441 pushes (0.036 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 2
• try: 11
• mozilla-beta: 2
• mozilla-central: 1

Platform breakdown:

• windows10-64-shippable: 2
• windows10-64-shippable-qr: 1
• linux64: 2
• macosx1014-64: 2
• windows10-64-qr: 3
• windows10-64: 3
• linux1804-64: 2
• windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-02-17&endday=2020-02-17&tree=all

Comment 44

[Andrew Sutherland [:asuth] (he/him)]

Even though this only seems to reliably reproduce on Windows, I'm going to quickly attempt to repro under pernosco locally with chaos mode and verify as this doesn't seem platform specific and the high repro rate on android in general would suggest that it's a scheduling issue that chaos mode should be able to reproduce.

Comment 45

[Andrew Sutherland [:asuth] (he/him)]

Various attempts at reproducing under pernosco didn't succeed[1], but here's a pernosco trace of a successful run:
https://pernos.co/debug/w_vJj-NADauaq-uyT_UKcA/index.html

1: It's probably worth retrying under "--verify". My verify run ended with ambiguous/confusing output and I had insufficient scrollback to identify what happened.

Comment 46

[Intermittent Failures Robot]

47 failures in 4045 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #19 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 23
• try: 13
• mozilla-beta: 5
• mozilla-central: 6

Platform breakdown:

• windows10-64-shippable: 9
• linux64: 2
• macosx1014-64: 3
• windows10-64-qr: 19
• windows10-64: 5
• linux1804-64: 2
• windows10-64-shippable-qr: 4
• windows10-64-devedition: 1
• linux1804-64-shippable: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-02-17&endday=2020-02-23&tree=all

Comment 47

[Intermittent Failures Robot]

23 failures in 5233 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• autoland: 15
• try: 2
• mozilla-beta: 1

Platform breakdown:

• windows10-64-shippable-qr: 4
• windows10-64-qr: 10
• windows10-64: 3
• windows10-64-devedition: 2
• windows10-64-ccov: 1
• windows10-64-shippable: 2
• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-02-24&endday=2020-03-01&tree=all

Comment 48

[Andrei Ciure[:aciure]]

There are 24 failures associated to this bug in the last 7 days. These are occurring on windows10-64 debug builds.

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

Comment 49

[Intermittent Failures Robot]

51 failures in 5483 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #21 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 37
• try: 8
• mozilla-central: 5
• mozilla-release: 1

Platform breakdown:

• linux1804-64-qr: 1
• windows10-64: 5
• windows10-64-shippable-qr: 13
• windows10-64-qr: 22
• windows7-32-shippable: 1
• windows10-64-shippable: 8
• windows10-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-03-02&endday=2020-03-08&tree=all

Comment 50

[Dorel Luca [:dluca]]

This bug failed 50 times in the last 7 days. Occurs on linux1804-64, linux1804-64-qr, linux1804-64-shippable-qr, windows10-64, windows10-64-ccov, windows10-64-qr, windows10-64-shippable, windows10-64-shippable-qr and windows7-32-shippable on opt and debug build types.

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

INFO - TEST-START | dom/html/test/test_formSubmission.html
[task 2020-03-10T21:51:49.796Z] 21:51:49     INFO - TEST-INFO | started process screenshot
[task 2020-03-10T21:51:49.861Z] 21:51:49     INFO - TEST-INFO | screenshot: exit 0
[task 2020-03-10T21:51:49.861Z] 21:51:49     INFO - <snipped 5717 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2020-03-10T21:51:49.862Z] 21:51:49     INFO - Buffered messages logged at 21:46:27
[task 2020-03-10T21:51:49.862Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.863Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.863Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.863Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.864Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.864Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.864Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.865Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.865Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.865Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.865Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.866Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.866Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.866Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.866Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.866Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.866Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.866Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.867Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.867Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.867Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.868Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.868Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.868Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.868Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.868Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.869Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.869Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.869Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.870Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.870Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.870Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.871Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.871Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.871Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.872Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.872Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.872Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.872Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.873Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.873Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.873Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.873Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.874Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.874Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.874Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.874Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.876Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.876Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.876Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.877Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.877Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.877Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.878Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.878Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.878Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.878Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.879Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.879Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.879Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.880Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.880Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.880Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.881Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.881Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.882Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.882Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.882Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.883Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.884Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.885Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct number of multipart items in send disabled form using XHR and FormData 
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO - Buffered messages finished
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Test timed out. 
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO -     hookupTests@SimpleTest/setup.js:294:12
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-03-10T21:51:49.887Z] 21:51:49     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-03-10T21:51:49.887Z] 21:51:49     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-03-10T21:51:49.887Z] 21:51:49     INFO -     hookup@SimpleTest/setup.js:270:20
[task 2020-03-10T21:51:49.887Z] 21:51:49     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1583875790%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | This test left a service worker registered without cleaning it up 
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     afterCleanup@SimpleTest/SimpleTest.js:1429:18
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     async*executeCleanupFunction@SimpleTest/SimpleTest.js:1474:7
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1494:3
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     killTest@SimpleTest/TestRunner.js:137:22
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     async*delayedKillTest@SimpleTest/TestRunner.js:173:17
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:171:17
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.799Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     hookupTests@SimpleTest/setup.js:294:12
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO -     hookup@SimpleTest/setup.js:270:20
[task 2020-03-10T21:51:50.800Z] 21:51:50     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1583875790%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Left over worker: http://mochi.test:8888/tests/dom/html/test/sw_formSubmission.js?proxy-fetch (scope: http://mochi.test:8888/tests/dom/html/test/) 
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     afterCleanup@SimpleTest/SimpleTest.js:1434:20
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     async*executeCleanupFunction@SimpleTest/SimpleTest.js:1474:7
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1494:3
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     killTest@SimpleTest/TestRunner.js:137:22
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     async*delayedKillTest@SimpleTest/TestRunner.js:173:17
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:171:17
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.801Z] 21:51:50     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO -     RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO -     RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO -     hookupTests@SimpleTest/setup.js:294:12
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO -     hookup@SimpleTest/setup.js:270:20
[task 2020-03-10T21:51:50.802Z] 21:51:50     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1583875790%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-03-10T21:51:50.821Z] 21:51:50     INFO - GECKO(9708) | MEMORY STAT | vsize 2104034MB | vsizeMaxContiguous 66407432MB | residentFast 124MB | heapAllocated 15MB
[task 2020-03-10T21:51:50.830Z] 21:51:50     INFO - TEST-OK | dom/html/test/test_formSubmission.html | took 325293ms

Jens: Can you have someone take a look at this bug?

Comment 51

[Intermittent Failures Robot]

16 failures in 1083 pushes (0.015 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 15
• try: 1

Platform breakdown:

• windows10-64-shippable-qr: 5
• windows10-64: 3
• linux1804-64-shippable-qr: 1
• windows10-64-qr: 2
• linux1804-64: 1
• windows10-64-shippable: 3
• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-03-10&endday=2020-03-10&tree=all

Comment 52

[Jens Stutte [:jstutte]]

FWIW, I see the same 3 lines of log repeated quite a lot:

[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 

and the

[task 2020-03-10T21:51:49.862Z] 21:51:49     INFO - Buffered messages logged at 21:46:27
... 100 lines of messages ...
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO - Buffered messages finished

suggests, that we see only the last 100 lines of much more output produced in approximately 5 minutes.

Is this the intended behavior of this test? It looks more like an endless loop triggered by whatever?

Comment 53

[Jens Stutte [:jstutte]]

In another instance I see a similar behavior but with different output lines:

[task 2020-03-10T17:38:00.587Z] 17:38:00     INFO - <snipped 4514 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2020-03-10T17:38:00.588Z] 17:38:00     INFO - Buffered messages logged at 17:33:00
[task 2020-03-10T17:38:00.589Z] 17:38:00     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send augmented FormData 
[task 2020-03-10T17:38:00.590Z] 17:38:00     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send augmented FormData 
[task 2020-03-10T17:38:00.591Z] 17:38:00     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send augmented FormData 
...
[task 2020-03-10T17:38:00.647Z] 17:38:00     INFO - installing ServiceWorker: sw_formSubmission.js?proxy-fetch
[task 2020-03-10T17:38:00.647Z] 17:38:00     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | controlled by the SW we expected 
[task 2020-03-10T17:38:00.647Z] 17:38:00     INFO - became controlled by ServiceWorker.
[task 2020-03-10T17:38:00.648Z] 17:38:00     INFO - starting test variant: ServiceWorker that proxies all fetches
[task 2020-03-10T17:38:00.648Z] 17:38:00     INFO - Buffered messages finished

This starting test variant: ServiceWorker that proxies all fetches looks as if the test would get a step forward and really just take too much time?

Comment 54

[Andrew Sutherland [:asuth] (he/him)]

(In reply to Jens Stutte [:jstutte] from comment #53)

This starting test variant: ServiceWorker that proxies all fetches looks as if the test would get a step forward and really just take too much time?

This is the timestamps on the left side being really misleading. If you click on the mochitest-plain_raw.log artifact for any run you're interested in, the JSON structured logs contain the actual timestamps for the messages that got buffered. I got tricked by this before and made the same conclusion, but in fact the test really ends up idling for several minutes.

(In reply to Jens Stutte [:jstutte] from comment #52)

FWIW, I see the same 3 lines of log repeated quite a lot:

[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData 
[task 2020-03-10T21:51:49.875Z] 21:51:49     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData 

and the

[task 2020-03-10T21:51:49.862Z] 21:51:49     INFO - Buffered messages logged at 21:46:27
... 100 lines of messages ...
[task 2020-03-10T21:51:49.886Z] 21:51:49     INFO - Buffered messages finished

suggests, that we see only the last 100 lines of much more output produced in approximately 5 minutes.

Is this the intended behavior of this test? It looks more like an endless loop triggered by whatever?

The test checks a LOT of form fields. There's a loop here that iterates over the list of expected things, which frequently is this 126-line block of things to check.

Comment 55

[Intermittent Failures Robot]

47 failures in 5336 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #32 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 32
• mozilla-central: 7
• try: 5
• mozilla-beta: 3

Platform breakdown:

• windows10-64-qr: 12
• windows10-64-ccov: 2
• windows10-64-shippable: 11
• linux1804-64: 3
• windows10-64-shippable-qr: 5
• windows10-64: 7
• linux1804-64-shippable-qr: 1
• linux1804-64-qr: 1
• windows7-32-shippable: 3
• windows10-64-devedition: 1
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-03-09&endday=2020-03-15&tree=all

Comment 56

[Intermittent Failures Robot]

29 failures in 4955 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 10
• try: 6
• autoland: 10
• mozilla-release: 2
• mozilla-beta: 1

Platform breakdown:

• windows10-64-shippable: 3
• windows10-64-qr: 11
• windows10-64: 7
• windows10-64-shippable-qr: 5
• windows7-32: 1
• windows7-32-shippable: 1
• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-03-16&endday=2020-03-22&tree=all

Comment 57

[Andreea Pavel [:apavel]]

There are 29 total failures in the last 7 days on windows platforms.

recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=294279195&repo=autoland&lineNumber=6014

[task 2020-03-22T23:49:34.072Z] 23:49:34 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData
[task 2020-03-22T23:49:34.072Z] 23:49:34 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send form using XHR and FormData
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send form using XHR and FormData
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send form using XHR and FormData
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct number of multipart items in send disabled form using XHR and FormData
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - Buffered messages finished
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Test timed out.
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.073Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-03-22T23:49:34.074Z] 23:49:34 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1584920212%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-03-22T23:49:34.975Z] 23:49:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T23:49:34.975Z] 23:49:34 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | This test left a service worker registered without cleaning it up
[task 2020-03-22T23:49:34.975Z] 23:49:34 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-03-22T23:49:34.975Z] 23:49:34 INFO - afterCleanup@SimpleTest/SimpleTest.js:1429:18
[task 2020-03-22T23:49:34.975Z] 23:49:34 INFO - asyncexecuteCleanupFunction@SimpleTest/SimpleTest.js:1474:7
[task 2020-03-22T23:49:34.975Z] 23:49:34 INFO - SimpleTest.finish@SimpleTest/SimpleTest.js:1494:3
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - killTest@SimpleTest/TestRunner.js:137:22
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - asyncdelayedKillTest@SimpleTest/TestRunner.js:173:17
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:171:17
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-03-22T23:49:34.976Z] 23:49:34 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - EventHandlerNonNull@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1584920212%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Left over worker: http://mochi.test:8888/tests/dom/html/test/sw_formSubmission.js?proxy-fetch (scope: http://mochi.test:8888/tests/dom/html/test/)
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - afterCleanup@SimpleTest/SimpleTest.js:1434:20
[task 2020-03-22T23:49:34.977Z] 23:49:34 INFO - asyncexecuteCleanupFunction@SimpleTest/SimpleTest.js:1474:7
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - SimpleTest.finish@SimpleTest/SimpleTest.js:1494:3
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - killTest@SimpleTest/TestRunner.js:137:22
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - asyncdelayedKillTest@SimpleTest/TestRunner.js:173:17
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:171:17
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-03-22T23:49:34.978Z] 23:49:34 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-03-22T23:49:34.979Z] 23:49:34 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-03-22T23:49:34.979Z] 23:49:34 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-03-22T23:49:34.979Z] 23:49:34 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-03-22T23:49:34.979Z] 23:49:34 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-03-22T23:49:34.979Z] 23:49:34 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-03-22T23:49:34.979Z] 23:49:34 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-03-22T23:49:34.979Z] 23:49:34 INFO - EventHandlerNonNull@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1584920212%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-03-22T23:49:35.002Z] 23:49:35 INFO - GECKO(10272) | MEMORY STAT | vsize 2104021MB | vsizeMaxContiguous 66108903MB | residentFast 124MB | heapAllocated 15MB
[task 2020-03-22T23:49:35.012Z] 23:49:35 INFO - TEST-OK | dom/html/test/test_formSubmission.html | took 318135ms
[task 2020-03-22T23:49:35.030Z] 23:49:35 ERROR - /tests/dom/html/test/test_formSubmission.html logged result after SimpleTest.finish(): This test left a service worker registered without cleaning it up
[task 2020-03-22T23:49:35.030Z] 23:49:35 ERROR - /tests/dom/html/test/test_formSubmission.html logged result after SimpleTest.finish(): Left over worker: http://mochi.test:8888/tests/dom/html/test/sw_formSubmission.js?proxy-fetch (scope: http://mochi.test:8888/tests/dom/html/test/)

Andrew are there updates here?

Comment 58

[Intermittent Failures Robot]

27 failures in 4773 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• autoland: 12
• try: 7
• mozilla-beta: 3

Platform breakdown:

• windows10-64-qr: 7
• windows10-64-shippable: 7
• windows10-64-shippable-qr: 3
• windows10-64-devedition: 1
• windows7-32: 2
• windows10-64: 4
• windows7-32-shippable: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-03-23&endday=2020-03-29&tree=all

Comment 59

[Andrei Ciure[:aciure]]

There are 22 failures associated to this bug in the last 7 days. These are occurring on all windows platforms.

Any updates here?

Comment 60

[Intermittent Failures Robot]

25 failures in 5015 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12
• mozilla-central: 5
• try: 4
• mozilla-release: 3
• mozilla-beta: 1

Platform breakdown:

• linux1804-64-qr: 1
• windows7-32: 2
• windows10-64-shippable: 6
• windows10-64-qr: 3
• linux1804-64-shippable-qr: 2
• windows10-64: 5
• windows10-64-shippable-qr: 4
• windows7-32-devedition: 1
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-03-30&endday=2020-04-05&tree=all

Comment 61

[Intermittent Failures Robot]

18 failures in 4823 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• mozilla-central: 5
• try: 1
• mozilla-beta: 1

Platform breakdown:

• windows10-64-shippable: 4
• windows10-64-qr: 6
• windows10-64-shippable-qr: 3
• windows7-32-shippable: 1
• windows10-64: 3
• windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-04-06&endday=2020-04-12&tree=all

Comment 62

[Andrei Ciure[:aciure]]

There are 29 failures associated to this bug in the last 7 days. These are occurring on all windows platforms.

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

[task 2020-04-15T05:35:21.309Z] 05:35:21 INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct number of multipart items in disabled controls
[task 2020-04-15T05:35:21.309Z] 05:35:21 INFO - Buffered messages finished
[task 2020-04-15T05:35:21.309Z] 05:35:21 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Test timed out.
[task 2020-04-15T05:35:21.309Z] 05:35:21 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-04-15T05:35:21.309Z] 05:35:21 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-04-15T05:35:21.309Z] 05:35:21 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-04-15T05:35:21.309Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:46:13
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:59:28
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:55:3
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-04-15T05:35:21.310Z] 05:35:21 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1586928155%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-04-15T05:35:22.220Z] 05:35:22 INFO - Not taking screenshot here: see the one that was previously logged

Andrew can you take a look?

Comment 63

[Intermittent Failures Robot]

40 failures in 4528 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #41 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 24
• mozilla-central: 10
• try: 4
• mozilla-beta: 2

Platform breakdown:

• windows10-64: 12
• windows10-64-shippable-qr: 8
• windows10-64-qr: 4
• windows10-64-shippable: 8
• windows7-32-shippable: 3
• linux1804-64-shippable: 1
• windows10-64-ccov: 1
• windows7-32-devedition: 1
• windows10-64-devedition: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 64

[Intermittent Failures Robot]

33 failures in 5582 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

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

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

Repository breakdown:

• autoland: 19
• mozilla-central: 8
• mozilla-beta: 3
• try: 3

Platform breakdown:

• windows10-64-qr: 7
• windows10-64-shippable: 6
• windows10-64: 6
• windows10-64-shippable-qr: 6
• windows7-32-shippable: 3
• windows10-64-ccov: 2
• windows7-32-devedition: 2
• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 65

[Andrei Ciure[:aciure]]

There are 25 failures associated to this bug in the last 7 days. These are occurring on all windows platforms.

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

Comment 66

[Intermittent Failures Robot]

38 failures in 5423 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #43 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 25
• try: 6
• mozilla-central: 6
• mozilla-release: 1

Platform breakdown:

• windows7-32-shippable: 5
• windows10-64-qr: 6
• windows10-64-shippable: 13
• windows10-64-shippable-qr: 5
• windows10-64: 8
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-04-27&endday=2020-05-03&tree=all

Comment 67

[Bogdan Tara[:bogdan_tara | bogdant]]

In the last 7 days there have been 28 occurrences, most on windows10-64 (some on windows 7), build types asan, debug and opt.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=301526076&repo=autoland&lineNumber=5687

...
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct content type in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct name in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct content type in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Wrong number of headers in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | Correct value in send augmented FormData 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - installing ServiceWorker: sw_formSubmission.js?proxy-fetch
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - TEST-PASS | dom/html/test/test_formSubmission.html | controlled by the SW we expected 
[task 2020-05-09T08:00:35.003Z] 08:00:35     INFO - became controlled by ServiceWorker.
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO - starting test variant: ServiceWorker that proxies all fetches
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO - Buffered messages finished
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | Test timed out. 
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO -     hookupTests@SimpleTest/setup.js:294:12
[task 2020-05-09T08:00:35.004Z] 08:00:35     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:46:13
[task 2020-05-09T08:00:35.005Z] 08:00:35     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:59:28
[task 2020-05-09T08:00:35.005Z] 08:00:35     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:55:3
[task 2020-05-09T08:00:35.005Z] 08:00:35     INFO -     hookup@SimpleTest/setup.js:270:20
[task 2020-05-09T08:00:35.005Z] 08:00:35     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1589010174%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-05-09T08:00:35.927Z] 08:00:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-09T08:00:35.928Z] 08:00:35     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_formSubmission.html | This test left a service worker registered without cleaning it up 
[task 2020-05-09T08:00:35.928Z] 08:00:35     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-05-09T08:00:35.928Z] 08:00:35     INFO -     afterCleanup@SimpleTest/SimpleTest.js:1429:18
[task 2020-05-09T08:00:35.928Z] 08:00:35     INFO -     async*executeCleanupFunction@SimpleTest/SimpleTest.js:1474:7
[task 2020-05-09T08:00:35.928Z] 08:00:35     INFO -     SimpleTest.finish@SimpleTest/SimpleTest.js:1494:3
[task 2020-05-09T08:00:35.928Z] 08:00:35     INFO -     killTest@SimpleTest/TestRunner.js:137:22
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     async*delayedKillTest@SimpleTest/TestRunner.js:173:17
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:171:17
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-05-09T08:00:35.929Z] 08:00:35     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
...

Comment 68

[Intermittent Failures Robot]

35 failures in 5276 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #40 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 22
• try: 5
• mozilla-central: 6
• mozilla-beta: 2

Platform breakdown:

• linux1804-64-qr: 2
• macosx1014-64-shippable: 1
• windows10-64: 10
• windows7-32-shippable: 2
• windows10-64-shippable-qr: 8
• windows10-64-shippable: 7
• windows7-32-devedition: 1
• windows10-64-qr: 3
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-05-04&endday=2020-05-10&tree=all

Comment 69

[Razvan Maries]

There are 31 total failures in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-05-10&endday=2020-05-17&tree=trunk&bug=1588148

Failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=302646382&repo=autoland&lineNumber=5631

Comment 70

[Razvan Maries]

Andrew, are there any updates on this?

Comment 71

[Intermittent Failures Robot]

36 failures in 5488 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #41 most frequent failure this week.

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

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

Repository breakdown:

• mozilla-central: 4
• autoland: 23
• mozilla-release: 2
• try: 5
• mozilla-beta: 2

Platform breakdown:

• windows10-64: 9
• macosx1014-64: 1
• windows7-32-shippable: 2
• windows10-64-shippable: 7
• windows7-32: 1
• windows10-64-shippable-qr: 7
• windows10-64-qr: 4
• windows10-64-devedition: 3
• linux1804-64: 1
• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-05-11&endday=2020-05-17&tree=all

Comment 72

[Dorel Luca [:dluca]]

This bug failed 32 times in the last 7 days. Occurs on Window 10 platforms on debug and opt build types.

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=303563488&repo=mozilla-central&lineNumber=5233

Comment 73

[Intermittent Failures Robot]

41 failures in 4727 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #30 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 28
• mozilla-beta: 3
• try: 5
• mozilla-central: 5

Platform breakdown:

• windows10-64-shippable: 14
• windows10-64-qr: 12
• windows10-64: 8
• windows10-64-shippable-qr: 3
• macosx1014-64-shippable: 1
• windows7-32-shippable: 2
• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-05-18&endday=2020-05-24&tree=all

Comment 74

[Intermittent Failures Robot]

24 failures in 5458 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 17
• mozilla-beta: 1
• mozilla-central: 6

Platform breakdown:

• windows10-64-shippable-qr: 6
• windows10-64: 3
• windows10-64-shippable: 7
• linux1804-64-shippable-qr: 1
• windows7-32-shippable: 1
• windows10-64-qr: 4
• windows10-64-ccov: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-05-25&endday=2020-05-31&tree=all

Comment 75

[Intermittent Failures Robot]

2 failures in 4949 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• mozilla-release: 1

Platform breakdown:

• linux1804-64: 1
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-06-01&endday=2020-06-07&tree=all

Comment 76

[Intermittent Failures Robot]

1 failures in 4035 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1588148&startday=2020-06-15&endday=2020-06-21&tree=all

Comment 77

[Andrew McCreight [:mccr8]]

No occurances of this for over a year.