Closed Bug 1707401 Opened 4 years ago Closed 4 years ago

Intermittent toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Test timed out - | Uncaught exception received from previously timed out test - popupshown listener on #PopupAutoComplete not removed before the end of test

Categories

(Toolkit :: Password Manager, defect)

defect

Tracking

()

RESOLVED FIXED
Fission Milestone M8
Tracking Status
firefox-esr78 --- wontfix
firefox-esr91 --- wontfix
firefox90 --- wontfix
firefox91 --- wontfix
firefox92 --- verified

People

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

References

Details

(Keywords: intermittent-failure, Whiteboard: [retriggered][stockwell disabled] fission-soft-blocker)

Attachments

(1 file)

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=337627969&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fNmQbwK0RvWvbPMbGwlbJg/runs/0/artifacts/public/logs/live_backing.log


[task 2021-04-24T18:12:34.688Z] 18:12:34     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Got popup - 
[task 2021-04-24T18:12:34.689Z] 18:12:34     INFO - content window focused
[task 2021-04-24T18:12:34.689Z] 18:12:34     INFO - Buffered messages finished
[task 2021-04-24T18:12:34.689Z] 18:12:34     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Test timed out - 
[task 2021-04-24T18:12:34.689Z] 18:12:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-24T18:12:34.689Z] 18:12:34     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Uncaught exception received from previously timed out test - popupshown listener on #PopupAutoComplete not removed before the end of test
[task 2021-04-24T18:12:34.689Z] 18:12:34     INFO - GECKO(3512) | MEMORY STAT | vsize 2104190MB | vsizeMaxContiguous 65218091MB | residentFast 238MB | heapAllocated 94MB
[task 2021-04-24T18:12:34.691Z] 18:12:34     INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | took 45160ms```

This looks to have appeared after bug 1702281 landed - link.

Sam, could you please take a look?

Flags: needinfo?(sfoster)
Whiteboard: [retriggered]
Summary: Intermittent toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Uncaught exception received from previously timed out test - popupshown listener on #PopupAutoComplete not removed before the end of test → Intermittent toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Test timed out - | Uncaught exception received from previously timed out test - popupshown listener on #PopupAutoComplete not removed before the end of test

:dimi is looking into what seems to be a related issue in bug 1713910, so lets block on that and see if that also solves this.

Depends on: 1713910
Flags: needinfo?(sfoster)

Tentatively tracking this bug for Fission Milestone M8 since it blocks test bug 1713910, which is a Fission M8 bug.

Fission Milestone: --- → M8

Update:
There have been 49 failures within the last 7 days:
• 39 failures on Windows 10 x64 WebRender opt
• 10 failures on Windows 10 x64 WebRender Shippable opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=346202448&repo=autoland&lineNumber=8206

[task 2021-07-23T22:57:24.707Z] 22:57:24     INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js
[task 2021-07-23T22:57:28.748Z] 22:57:28     INFO - GECKO(3424) | must wait for focus in content
[task 2021-07-23T22:57:29.438Z] 22:57:29     INFO - GECKO(3424) | must wait for focus in content
[task 2021-07-23T22:57:29.906Z] 22:57:29     INFO - GECKO(3424) | must wait for focus in content
[task 2021-07-23T22:58:09.818Z] 22:58:09     INFO - TEST-INFO | started process screenshot
[task 2021-07-23T22:58:09.886Z] 22:58:09     INFO - TEST-INFO | screenshot: exit 0
[task 2021-07-23T22:58:09.886Z] 22:58:09     INFO - Buffered messages logged at 22:57:24
[task 2021-07-23T22:58:09.887Z] 22:58:09     INFO - Entering test bound common_initialize
[task 2021-07-23T22:58:09.887Z] 22:58:09     INFO - Leaving test bound common_initialize
[task 2021-07-23T22:58:09.887Z] 22:58:09     INFO - Entering test bound check_fluent_ids
[task 2021-07-23T22:58:09.888Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chrome included line1 - 
[task 2021-07-23T22:58:09.888Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chrome included line2 - 
[task 2021-07-23T22:58:09.889Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chrome replaced host - 
[task 2021-07-23T22:58:09.889Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chromium-edge included line1 - 
[task 2021-07-23T22:58:09.890Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chromium-edge included line2 - 
[task 2021-07-23T22:58:09.890Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chromium-edge replaced host - 
[task 2021-07-23T22:58:09.891Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chromium included line1 - 
[task 2021-07-23T22:58:09.891Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chromium included line2 - 
[task 2021-07-23T22:58:09.892Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete-import-logins-chromium replaced host - 
[task 2021-07-23T22:58:09.892Z] 22:58:09     INFO - Leaving test bound check_fluent_ids
[task 2021-07-23T22:58:09.892Z] 22:58:09     INFO - Entering test bound test_initialize
[task 2021-07-23T22:58:09.893Z] 22:58:09     INFO - Leaving test bound test_initialize
[task 2021-07-23T22:58:09.893Z] 22:58:09     INFO - Entering test bound import_suggestion_wizard
[task 2021-07-23T22:58:09.893Z] 22:58:09     INFO - Buffered messages logged at 22:57:28
[task 2021-07-23T22:58:09.894Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Got popup - 
[task 2021-07-23T22:58:09.894Z] 22:58:09     INFO - content window focused
[task 2021-07-23T22:58:09.895Z] 22:58:09     INFO - Console message: [JavaScript Error: "can't access property "flowId", this.activeSection is undefined" {file: "resource://autofill/FormAutofillContent.jsm" line: 897}]
[task 2021-07-23T22:58:09.895Z] 22:58:09     INFO - onPopupOpened@resource://autofill/FormAutofillContent.jsm:897:1
[task 2021-07-23T22:58:09.895Z] 22:58:09     INFO - popupStateChanged@resource://autofill/FormAutofillChild.jsm:85:29
[task 2021-07-23T22:58:09.895Z] 22:58:09     INFO - notifyListeners@resource://gre/actors/AutoCompleteChild.jsm:92:18
[task 2021-07-23T22:58:09.895Z] 22:58:09     INFO - receiveMessage@resource://gre/actors/AutoCompleteChild.jsm:68:14
[task 2021-07-23T22:58:09.895Z] 22:58:09     INFO - 
[task 2021-07-23T22:58:09.895Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete popup shown - 
[task 2021-07-23T22:58:09.896Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Got importable suggestion richlistitem - 
[task 2021-07-23T22:58:09.896Z] 22:58:09     INFO - Clicking on importable suggestion
[task 2021-07-23T22:58:09.896Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Wizard opened - 
[task 2021-07-23T22:58:09.897Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Direct migrate not used - 
[task 2021-07-23T22:58:09.897Z] 22:58:09     INFO - Buffered messages logged at 22:57:29
[task 2021-07-23T22:58:09.898Z] 22:58:09     INFO - Leaving test bound import_suggestion_wizard
[task 2021-07-23T22:58:09.898Z] 22:58:09     INFO - Entering test bound import_suggestion_learn_more
[task 2021-07-23T22:58:09.898Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Got popup - 
[task 2021-07-23T22:58:09.899Z] 22:58:09     INFO - content window focused
[task 2021-07-23T22:58:09.899Z] 22:58:09     INFO - Console message: [JavaScript Error: "can't access property "flowId", this.activeSection is undefined" {file: "resource://autofill/FormAutofillContent.jsm" line: 897}]
[task 2021-07-23T22:58:09.899Z] 22:58:09     INFO - onPopupOpened@resource://autofill/FormAutofillContent.jsm:897:1
[task 2021-07-23T22:58:09.899Z] 22:58:09     INFO - popupStateChanged@resource://autofill/FormAutofillChild.jsm:85:29
[task 2021-07-23T22:58:09.900Z] 22:58:09     INFO - notifyListeners@resource://gre/actors/AutoCompleteChild.jsm:92:18
[task 2021-07-23T22:58:09.900Z] 22:58:09     INFO - receiveMessage@resource://gre/actors/AutoCompleteChild.jsm:68:14
[task 2021-07-23T22:58:09.900Z] 22:58:09     INFO - 
[task 2021-07-23T22:58:09.900Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | autocomplete popup shown - 
[task 2021-07-23T22:58:09.901Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Got importable learn more richlistitem - 
[task 2021-07-23T22:58:09.901Z] 22:58:09     INFO - Clicking on importable learn more
[task 2021-07-23T22:58:09.901Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Support tab opened - 
[task 2021-07-23T22:58:09.902Z] 22:58:09     INFO - Leaving test bound import_suggestion_learn_more
[task 2021-07-23T22:58:09.902Z] 22:58:09     INFO - Entering test bound import_suggestion_migrate
[task 2021-07-23T22:58:09.902Z] 22:58:09     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Got popup - 
[task 2021-07-23T22:58:09.903Z] 22:58:09     INFO - content window focused
[task 2021-07-23T22:58:09.903Z] 22:58:09     INFO - Buffered messages finished
[task 2021-07-23T22:58:09.903Z] 22:58:09     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Test timed out - 
[task 2021-07-23T22:58:09.904Z] 22:58:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-23T22:58:09.904Z] 22:58:09     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Uncaught exception received from previously timed out test - popupshown listener on #PopupAutoComplete not removed before the end of test
[task 2021-07-23T22:58:09.905Z] 22:58:09     INFO - GECKO(3424) | MEMORY STAT | vsize 2104197MB | vsizeMaxContiguous 65055220MB | residentFast 239MB | heapAllocated 93MB
[task 2021-07-23T22:58:09.905Z] 22:58:09     INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | took 45148ms
[task 2021-07-23T22:58:09.905Z] 22:58:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-23T22:58:09.906Z] 22:58:09     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autocomplete_import.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/components/passwordmgr/test/browser/form_basic.html - 
[task 2021-07-23T22:58:09.906Z] 22:58:09     INFO - checking window state
[task 2021-07-23T22:58:09.907Z] 22:58:09     INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_autocomplete_insecure_warning.js
[task 2021-07-23T22:58:10.229Z] 22:58:10     INFO - GECKO(3424) | must wait for focus in content
[task 2021-07-23T22:58:10.474Z] 22:58:10     INFO - GECKO(3424) | MEMORY STAT | vsize 2104199MB | vsizeMaxContiguous 65055220MB | residentFast 243MB | heapAllocated 97MB
[task 2021-07-23T22:58:10.474Z] 22:58:10     INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_autocomplete_insecure_warning.js | took 576ms
[task 2021-07-23T22:58:10.485Z] 22:58:10     INFO - checking window state
[task 2021-07-23T22:58:10.496Z] 22:58:10     INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_autocomplete_master_password.js
Flags: needinfo?(sfoster)
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disable-recommended][stockwell needswork:owner]
Whiteboard: [retriggered][stockwell disable-recommended][stockwell needswork:owner] → [retriggered][stockwell needswork:owner]
Assignee: nobody → imoraru
Status: NEW → ASSIGNED
Pushed by imoraru@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/ec3b8abd3e80 disable browser_autocomplete_import.js on windows_10_64_qr for frequent failures. r=intermittent-reviewers,bhearsum DONTBUILD
Status: ASSIGNED → NEW
Keywords: leave-open
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disabled]
Assignee: imoraru → nobody

This bug is a soft blocker for Fission M8. We'd like to fix it before our M8 Release experiment, but we won't delay the experiment waiting for it.

Whiteboard: [retriggered][stockwell disabled] → [retriggered][stockwell disabled] fission-soft-blocker

Dimi just landed a fix for bug 1713910, which might also fix this bug. Let's wait a few days to see if these browser_autocomplete_import.js test failures reappear now that Dimi has re-enabled the test.

(In reply to Chris Peterson [:cpeterson] from comment #24)

Dimi just landed a fix for bug 1713910, which might also fix this bug. Let's wait a few days to see if these browser_autocomplete_import.js test failures reappear now that Dimi has re-enabled the test.

Dimi, you landed your fix for browser_autocomplete_import.js bug 1713910 on 2021-07-28, but we're still seeing some intermittent test failures after that date:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1707401&startday=2021-07-28&endday=2021-08-02&tree=all

What do you recommend we do?

Flags: needinfo?(dlee)

Tentatively assigning to Dimi because he fixed related test bug 1713910.

Dimi, if you are not the right engineer to work on this bug, just let me know.

Assignee: nobody → dlee

(In reply to Chris Peterson [:cpeterson] from comment #26)

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1707401&startday=2021-07-28&endday=2021-08-02&tree=all

What do you recommend we do?

From the link, it looks like those failures are appeared in mozilla-beta, esr or release.
So I guess that means the patch in bug 1713910 did fix this issue?

Flags: needinfo?(sfoster)
Flags: needinfo?(dlee)

(In reply to Dimi Lee [:dimi] from comment #28)

From the link, it looks like those failures are appeared in mozilla-beta, esr or release.

To be clear, it means the failures haven't appeared on mozilla-central.

(In reply to Dimi Lee [:dimi] from comment #28)

From the link, it looks like those failures are appeared in mozilla-beta, esr or release.
So I guess that means the patch in bug 1713910 did fix this issue?

Oh! You are correct. I didn't see that those failures didn't happen on mozilla-central.

That's great news. I'll resolve this bug as fixed by bug 1713910.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: