Closed Bug 1420103 Opened 8 years ago Closed 2 years ago

Intermittent toolkit/components/satchel/test/test_form_autocomplete.html | Autocomplete popup not expected during test 253

Categories

(Toolkit :: Form Manager, defect, P3)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1823123

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled] [fxcm-debt])

There are 34 failures asociated to this bug in the last 7 days. These are occurring on Windows 10-64 and OSX 10-10 debug builds. :MattN can you take a look at this?
Flags: needinfo?(MattN+bmo)
Whiteboard: [stockwell needswork]
There are 32 failures in the last 7 days. It failed only on osx-10 debug. Recent log: https://treeherder.mozilla.org/logviewer.html#?job_id=211246714&repo=autoland
Whiteboard: [stockwell unknown] → [stockwell needswork]
In the last 7 days, there have been 36 failures. Most of the failures are on osx-10-10 and windows7-32. Affected build type: debug An example of a recent log file: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=218406761&repo=autoland&lineNumber=32472 And the relevant part of the log: 20:51:34 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | 253 Checking menu entry #0 20:51:34 INFO - popup shown for test 253 20:51:34 INFO - Buffered messages finished 20:51:34 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/satchel/test/test_form_autocomplete.html | Autocomplete popup not expected during test 253 20:51:34 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:7 20:51:34 INFO - popupShownListener@toolkit/components/satchel/test/test_form_autocomplete.html:218:5 20:51:34 INFO - satchelCommonSetup/<@toolkit/components/satchel/test/satchel_common.js:257:7 20:51:34 INFO - Async*satchelCommonSetup@toolkit/components/satchel/test/satchel_common.js:253:19 20:51:34 INFO - @toolkit/components/satchel/test/satchel_common.js:274:1 20:51:34 INFO - GECKO(2424) | --DOMWINDOW == 2 (0106B000) [pid = 5156] [serial = 2] [outer = 00000000] [url = about:blank] :MattN As you are the triage owner of this component, could you please take a look at this? Thank you!
Whiteboard: [stockwell unknown] → [stockwell unknown][stockwell needswork]
Flags: needinfo?(MattN+bmo)
Priority: P5 → P3

There are 24 total failures in the last 7 days.

Rcent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=231771428&repo=autoland&lineNumber=34706

23:53:33 INFO - TEST-START | toolkit/components/satchel/test/test_form_autocomplete.html
23:53:33 INFO - GECKO(1548) | ++DOMWINDOW == 14 (000001EA6904D800) [pid = 3100] [serial = 16] [outer = 000001EA68D50000]
23:53:33 INFO - GECKO(1548) | [Parent 10404, Main Thread] WARNING: 'aRv.Failed()', file z:/build/build/src/dom/ipc/StructuredCloneData.cpp, line 120
23:53:33 INFO - GECKO(1548) | TEST-PASS | http://mochi.test:8888/tests/toolkit/components/satchel/test/parent_utils.js | Got autocomplete popup - [object XULPopupElement] == true
23:53:36 INFO - GECKO(1548) | --DOMWINDOW == 11 (00000272A60C4800) [pid = 10404] [serial = 4] [outer = 0000000000000000] [url = about:blank]
23:53:36 INFO - GECKO(1548) | --DOMWINDOW == 10 (00000272A9077800) [pid = 10404] [serial = 10] [outer = 0000000000000000] [url = about:blank]
23:53:38 INFO - TEST-INFO | started process screenshot
23:53:39 INFO - TEST-INFO | screenshot: exit 0
23:53:39 INFO - <snipped 87 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
23:53:39 INFO - Buffered messages logged at 23:53:36
23:53:39 INFO - expecting popup for test 56
23:53:39 INFO - popup shown for test 56
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | Starting test #57
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | 57 Checking length of expected menu
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | 57 Checking menu entry #0
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | 57 Checking menu entry #1
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | Checking form1 input

23:53:39 INFO - popup shown for test 253
23:53:39 INFO - Buffered messages finished
23:53:39 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/satchel/test/test_form_autocomplete.html | Autocomplete popup not expected during test 253
23:53:39 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
23:53:39 INFO - popupShownListener@toolkit/components/satchel/test/test_form_autocomplete.html:220:5
23:53:39 INFO - satchelCommonSetup/<@toolkit/components/satchel/test/satchel_common.js:267:7
23:53:39 INFO - Async*satchelCommonSetup@toolkit/components/satchel/test/satchel_common.js:263:33
23:53:39 INFO - @toolkit/components/satchel/test/satchel_common.js:284:1
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | Starting test #254
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | undefined Checking length of expected menu
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | undefined Checking menu entry #0
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | undefined Checking menu entry #1
23:53:39 INFO - expecting popup for test 254
23:53:39 INFO - popup shown for test 254
23:53:39 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | Starting test #255

Matthew can you assign someone?

Flags: needinfo?(MattN+bmo)
Whiteboard: [stockwell unknown][stockwell needswork] → [stockwell unknown][stockwell needswork:owner]

There have been 34 failures within the last 7 days:

  • 11 failures on OS X 10.10 debug
  • 6 failures on Windows 10 x64 debug
  • 2 failures on Windows 10 x64 ccov debug
  • 15 failures on Windows 7 debug

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

19:03:42 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | 253 Checking menu entry #0
19:03:42 INFO - popup shown for test 253
19:03:42 INFO - Buffered messages finished
19:03:42 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/satchel/test/test_form_autocomplete.html | Autocomplete popup not expected during test 253
19:03:42 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
19:03:42 INFO - popupShownListener@toolkit/components/satchel/test/test_form_autocomplete.html:220:5
19:03:42 INFO - satchelCommonSetup/<@toolkit/components/satchel/test/satchel_common.js:267:7
19:03:42 INFO - Async*satchelCommonSetup@toolkit/components/satchel/test/satchel_common.js:263:33
19:03:42 INFO - @toolkit/components/satchel/test/satchel_common.js:284:1
19:03:42 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | Starting test #254
19:03:42 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | undefined Checking length of expected menu
19:03:42 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | undefined Checking menu entry #0
19:03:42 INFO - TEST-PASS | toolkit/components/satchel/test/test_form_autocomplete.html | undefined Checking menu entry #1
19:03:42 INFO - expecting popup for test 254

In the last 7 days, there have been 51 failures.

The failures are on osx-10-10, windows10-64, windows7-32 platfoms.
Affected build types: debug.

An example of a recent log file:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=236132494&repo=autoland&lineNumber=30822

And the relevant part of the log:
15:34:18 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_insecure_form_field_autocomplete.html | Checking form12 password is:
15:34:18 INFO - Buffered messages finished
15:34:18 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_insecure_form_field_autocomplete.html | Test timed out.
15:34:18 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
15:34:18 INFO - reportError@SimpleTest/TestRunner.js:121:22
15:34:18 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
15:34:18 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
15:34:18 INFO - promise callback
TestRunner.runTests@SimpleTest/TestRunner.js:368:50
15:34:18 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
15:34:18 INFO - RunSet.runall@SimpleTest/setup.js:180:12
15:34:18 INFO - hookupTests@SimpleTest/setup.js:273:12
15:34:18 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
15:34:18 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
15:34:18 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
15:34:18 INFO - hookup@SimpleTest/setup.js:253:5
15:34:18 INFO - EventHandlerNonNull
@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2Flz%2Fwtcnqc_971n9v63kdknx17f000000x%2FT&cleanupCrashes=true:11:1
15:34:19 INFO - GECKO(1248) | Removing 1 popup notifications.
15:34:19 INFO - GECKO(1248) | MEMORY STAT | vsize 4101MB | residentFast 120MB | heapAllocated 12MB

:Matthew as you are the triage owner of this component, could you please take a look at this?
Thank you!

Whiteboard: [stockwell unknown][stockwell needswork:owner] → [stockwell unknown][stockwell needswork]
Assignee: nobody → MattN+bmo
Status: NEW → ASSIGNED
Flags: needinfo?(MattN+bmo)

The stack of the popup closing is:

0 handleEvent(evt = [object MouseEvent]) ["resource://gre/modules/AutoCompletePopup.jsm":138:15]
this = [object Object]
1 closePopup() ["resource://gre/modules/AutoCompletePopup.jsm":232:23]
this = [object Object]
2 receiveMessage(message = [object Object]) ["resource://gre/modules/AutoCompletePopup.jsm":290:13] //FormAutoComplete:ClosePopup
this = [object Object]

Then the popup re-opens causing the test failure.

I'll need to debug in the content process to figure out what's sending the message.

Based on the above comment, this is being worked on, switching the stockwell tag.

Whiteboard: [stockwell disable-recommended] → [stockwell needswork:owner]
Whiteboard: [stockwell disable-recommended] → [stockwell needswork:owner]
Whiteboard: [stockwell needswork:owner] → [stockwell needswork]

There are 37 failures associated to this bug in the last 7 days. These are occurring on windows7-32 and osx-10-10 debug builds.

Whiteboard: [stockwell disable-recommended] → [stockwell needswork]

:MattN , there are 79 failures in in the last 7 days.
Do you have any updates, should we disable the test until there will be a fix?

Flags: needinfo?(MattN+bmo)

I will disable just test 253.

Flags: needinfo?(MattN+bmo)
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Pushed by mozilla@noorenberghe.ca: https://hg.mozilla.org/integration/mozilla-inbound/rev/f1b137efc2a3 Make an unexpected popup during test_form_autocomplete.html #253 a TODO. r=disable-intermittent
Assignee: MattN+bmo → nobody
Status: ASSIGNED → NEW
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla68
Status: RESOLVED → REOPENED
Keywords: leave-open
Resolution: FIXED → ---
Target Milestone: mozilla68 → ---
Severity: normal → S3

Should be fixed by Bug 1823123

Status: REOPENED → RESOLVED
Closed: 6 years ago2 years ago
Duplicate of bug: 1823123
Resolution: --- → DUPLICATE
Whiteboard: [stockwell disabled] → [stockwell disabled] [fxcm-debt]
You need to log in before you can comment on or make changes to this bug.