Open Bug 1554959 Opened 5 years ago Updated 2 years ago

Intermittent toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Test timed out.

Categories

(Toolkit :: Password Manager, defect, P3)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, leave-open, regression, Whiteboard: [stockwell disabled])

Attachments

(1 file, 1 obsolete file)

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


08:46:27 INFO - TEST-START | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html
08:46:27 INFO - GECKO(2413) | 1559033187727 Marionette TRACE [6442450945] Frame script loaded
08:46:27 INFO - GECKO(2413) | 1559033187728 Marionette ERROR [6442450945] No reply from Marionette:Register
08:46:28 INFO - GECKO(2413) | TEST-PASS | http://mochi.test:8888/tests/toolkit/components/passwordmgr/test/mochitest/parent_utils.js | Got autocomplete popup - {"mInput":null,"mPopupOpen":false,"_currentIndex":0} == true
08:46:28 INFO - GECKO(2413) | TEST-PASS | http://mochi.test:8888/tests/toolkit/components/passwordmgr/test/mochitest/pwmgr_common_parent.js | Access LoginManager - true == true
08:46:28 INFO - GECKO(2413) | TEST-PASS | http://mochi.test:8888/tests/toolkit/components/passwordmgr/test/mochitest/pwmgr_common_parent.js | Not expecting logins to be present - 0 == 0
08:46:28 INFO - GECKO(2413) | TEST-PASS | http://mochi.test:8888/tests/toolkit/components/passwordmgr/test/mochitest/pwmgr_common_parent.js | Checking for successful init login - 0 == 0
08:46:28 INFO - GECKO(2413) | TEST-PASS | http://mochi.test:8888/tests/toolkit/components/passwordmgr/test/mochitest/pwmgr_common_parent.js | Checking for no disabled hosts - 0 == 0
08:46:28 INFO - GECKO(2413) | console.warn: nsLoginManager: "searchLogins: formSubmitURL or httpRealm is recommended"
08:46:39 INFO - GECKO(2413) | 2019-05-28 08:46:39.801 firefox[2413:19872] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightly.savedState/window_1.data: No such file or directory (2)
08:46:45 INFO - GECKO(2413) | console.info: services.settings: Initialize Remote Settings
08:51:27 INFO - TEST-INFO | started process screencapture
08:51:27 INFO - TEST-INFO | screencapture: exit 0
08:51:27 INFO - Buffered messages logged at 08:46:28
08:51:27 INFO - add_task | Entering test test_autocomplete_http
08:51:27 INFO - test_autocomplete_http, setup with http://example.org/tests/toolkit/components/passwordmgr/test/mochitest/form_basic.html
08:51:27 INFO - got logins: https://example.org,https://example.org,http://example.org,http://example.org
08:51:27 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Checking form-basic username is:
08:51:27 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Checking form-basic password is:
08:51:27 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Check popup is initially closed
08:51:27 INFO - Buffered messages finished
08:51:27 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Test timed out.
08:51:27 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
08:51:27 INFO - reportError@SimpleTest/TestRunner.js:121:22
08:51:27 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
08:51:27 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
08:51:27 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
08:51:27 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
08:51:27 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
08:51:27 INFO - RunSet.runall@SimpleTest/setup.js:180:12
08:51:27 INFO - hookupTests@SimpleTest/setup.js:273:12
08:51:27 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
08:51:27 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
08:51:27 INFO - EventHandlerNonNull
getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
08:51:27 INFO - hookup@SimpleTest/setup.js:253:5
08:51:27 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2Fgg%2F3_0br42x6h9gd_mmr72dffr000000w%2FT&cleanupCrashes=true:11:1
08:51:28 INFO - GECKO(2413) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
08:51:28 INFO - GECKO(2413) | MEMORY STAT | vsize 5175MB | residentFast 100MB | heapAllocated 9MB
08:51:28 INFO - TEST-OK | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | took 301094ms

I guess we can disable this for Linux debug for now.

Priority: P5 → P1
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Pushed by malexandru@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/3378e367e1d9
Disable test_autocomplete_https_upgrade.html on Linux debug r=jmaher

There are 27 total failures in the last 6 days on:

*debug: windows10-64-qr, windows10-64-ccov,
*asan: windows10-64, linux64,
*opt: linux64-shippable-qr, windows10-64-shippable-qr

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

11:44:15 INFO - TEST-START | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html
11:44:15 INFO - GECKO(5788) | ++DOMWINDOW == 7 (000002870FDABC00) [pid = 5696] [serial = 7] [outer = 000002870FD395C0]
11:44:16 INFO - GECKO(5788) | [Parent 1812, URL Classifier] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/toolkit/components/url-classifier/LookupCache.cpp, line 882
11:44:16 INFO - [9340, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662

11:49:15 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Check popup is initially closed
11:49:15 INFO - Buffered messages finished
11:49:15 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Test timed out.
11:49:15 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
11:49:15 INFO - reportError@SimpleTest/TestRunner.js:121:22
11:49:15 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
11:49:15 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:49:15 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
11:49:15 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
11:49:15 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
11:49:15 INFO - RunSet.runall@SimpleTest/setup.js:180:12
11:49:15 INFO - hookupTests@SimpleTest/setup.js:273:12
11:49:15 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:5
11:49:15 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:11
11:49:15 INFO - EventHandlerNonNull
getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
11:49:15 INFO - hookup@SimpleTest/setup.js:253:5
11:49:15 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1563706031%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
11:49:16 INFO - GECKO(5788) | MEMORY STAT | vsize 2104105MB | vsizeMaxContiguous 86341530MB | residentFast 161MB | heapAllocated 11MB
11:49:16 INFO - TEST-OK | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | took 301232ms

Matthew can you take a look or assign someone to?

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

Hi Matt, this is still an ongoing issue with 75 failures in the last 30 days.

Can you please take a look?

Flags: needinfo?(MattN+bmo)
Flags: needinfo?(MattN+bmo)
Flags: qe-verify-

The increased frequency since July 4th was caused by bug 1541557 part 5 changing things that need to be synchronous to be async :(

Regressed by: 1541557

Thank you Matthew.

Kris please take a look at this.

Flags: needinfo?(MattN+bmo) → needinfo?(kmaglione+bmo)

There have been 27 failures in the last 7 days.

Affected platforms are:

asan: linux64;windows10-64
opt: linux64-shippable; macosx1014-64-shippable; windows10-64-shippable
debug: macosx1014-64

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

There are 32 total failures in the last 7 days on

asan: linux64;windows10-64
opt: linux64-shippable; macosx1014-64-shippable; windows10-64-shippable
debug: macosx1014-64

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

[task 2019-10-10T19:41:40.042Z] 19:41:40 INFO - TEST-START | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html
[task 2019-10-10T19:41:41.316Z] 19:41:41 INFO - GECKO(1977) | console.warn: LoginManager: "searchLogins: formActionOrigin or httpRealm is recommended"
[task 2019-10-10T19:42:06.914Z] 19:42:06 INFO - GECKO(1977) | 2019-10-10 19:42:06.887 firefox[1977:16608] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightly.savedState/window_1.data: No such file or directory (2)
[task 2019-10-10T19:46:40.627Z] 19:46:40 INFO - TEST-INFO | started process screencapture
[task 2019-10-10T19:46:40.741Z] 19:46:40 INFO - TEST-INFO | screencapture: exit 0
[task 2019-10-10T19:46:40.741Z] 19:46:40 INFO - Buffered messages logged at 19:41:41
[task 2019-10-10T19:46:40.741Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Got autocomplete popup - {"mInput":null,"mPopupOpen":false,"_currentIndex":0} == true
[task 2019-10-10T19:46:40.741Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Access LoginManager - true == true
[task 2019-10-10T19:46:40.741Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Not expecting logins to be present - 0 == 0
[task 2019-10-10T19:46:40.741Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Checking for successful init login - 0 == 0
[task 2019-10-10T19:46:40.742Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Checking for no disabled hosts - 0 == 0
[task 2019-10-10T19:46:40.742Z] 19:46:40 INFO - add_task | Entering test test_autocomplete_http
[task 2019-10-10T19:46:40.742Z] 19:46:40 INFO - test_autocomplete_http, setup with http://example.org/tests/toolkit/components/passwordmgr/test/mochitest/form_basic.html
[task 2019-10-10T19:46:40.742Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Checking form-basic username is:
[task 2019-10-10T19:46:40.742Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Checking form-basic password is:
[task 2019-10-10T19:46:40.744Z] 19:46:40 INFO - got logins: https://example.org,https://example.org,http://example.org,http://example.org
[task 2019-10-10T19:46:40.744Z] 19:46:40 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Check popup is initially closed
[task 2019-10-10T19:46:40.744Z] 19:46:40 INFO - Buffered messages finished
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | Test timed out.
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-10T19:46:40.748Z] 19:46:40 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - EventHandlerNonNull
getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - hookup@SimpleTest/setup.js:253:20
[task 2019-10-10T19:46:40.749Z] 19:46:40 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2Ftw%2Fsldlt3b133n8qj1hs8vldpz4000017%2FT&cleanupCrashes=true:11:1
[task 2019-10-10T19:46:41.703Z] 19:46:41 INFO - GECKO(1977) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2019-10-10T19:46:41.706Z] 19:46:41 INFO - GECKO(1977) | MEMORY STAT | vsize 7359MB | residentFast 126MB | heapAllocated 24MB
[task 2019-10-10T19:46:41.706Z] 19:46:41 INFO - TEST-OK | toolkit/components/passwordmgr/test/mochitest/test_autocomplete_https_upgrade.html | took 301708ms

Geoff should we disable this? https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-09-11&endday=2019-10-11&tree=trunk&bug=1554959

Flags: needinfo?(gbrown)

Yes, I think so.

Flags: needinfo?(gbrown)
Attachment #9102802 - Attachment is obsolete: true

Matt changed the test so there's no need for a disable as there are no other occurrences in the last 7 days.

Flags: needinfo?(kmaglione+bmo)
Whiteboard: [stockwell disabled][stockwell needswork:owner] → [stockwell disabled]

Lowering to P3 as we are not doing this any time soon.

Priority: P1 → P3
Has Regression Range: --- → yes
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: